{"level":"warn","ts":"2026-04-13T17:11:14.554209Z","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-13T17:11:14.554331Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.242:2380","--initial-cluster=instance=https://199.19.213.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.242: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-13T17:11:14.554425Z","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-13T17:11:14.554443Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.242:2380"]} {"level":"info","ts":"2026-04-13T17:11:14.554472Z","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-13T17:11:14.555451Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"]} {"level":"info","ts":"2026-04-13T17:11:14.555595Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.242:2380"],"listen-peer-urls":["https://199.19.213.242:2380"],"advertise-client-urls":["https://199.19.213.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.242: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-13T17:11:14.559628Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.644135ms"} {"level":"info","ts":"2026-04-13T17:11:14.565167Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ad427454a89beddc","cluster-id":"edc6037504b39883"} {"level":"info","ts":"2026-04-13T17:11:14.565241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T17:11:14.565257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became follower at term 0"} {"level":"info","ts":"2026-04-13T17:11:14.565267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ad427454a89beddc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T17:11:14.565275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became follower at term 1"} {"level":"info","ts":"2026-04-13T17:11:14.56531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=(12484669023979302364)"} {"level":"warn","ts":"2026-04-13T17:11:14.57349Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T17:11:14.574931Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T17:11:14.575993Z","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-13T17:11:14.577985Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ad427454a89beddc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T17:11:14.578091Z","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-13T17:11:14.578148Z","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-13T17:11:14.578159Z","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-13T17:11:14.57814Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ad427454a89beddc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T17:11:14.582553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=(12484669023979302364)"} {"level":"info","ts":"2026-04-13T17:11:14.58289Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"edc6037504b39883","local-member-id":"ad427454a89beddc","added-peer-id":"ad427454a89beddc","added-peer-peer-urls":["https://199.19.213.242:2380"]} {"level":"info","ts":"2026-04-13T17:11:14.58404Z","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-13T17:11:14.584115Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.242:2380"} {"level":"info","ts":"2026-04-13T17:11:14.584163Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.242:2380"} {"level":"info","ts":"2026-04-13T17:11:14.58438Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ad427454a89beddc","initial-advertise-peer-urls":["https://199.19.213.242:2380"],"listen-peer-urls":["https://199.19.213.242:2380"],"advertise-client-urls":["https://199.19.213.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T17:11:14.584429Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T17:11:14.666419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T17:11:14.666454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T17:11:14.666468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc received MsgPreVoteResp from ad427454a89beddc at term 1"} {"level":"info","ts":"2026-04-13T17:11:14.666481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became candidate at term 2"} {"level":"info","ts":"2026-04-13T17:11:14.66649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc received MsgVoteResp from ad427454a89beddc at term 2"} {"level":"info","ts":"2026-04-13T17:11:14.666503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became leader at term 2"} {"level":"info","ts":"2026-04-13T17:11:14.666512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ad427454a89beddc elected leader ad427454a89beddc at term 2"} {"level":"info","ts":"2026-04-13T17:11:14.667513Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:11:14.669459Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"edc6037504b39883","local-member-id":"ad427454a89beddc","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:11:14.669531Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:11:14.669552Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:11:14.669579Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ad427454a89beddc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.242:2379]}","request-path":"/0/members/ad427454a89beddc/attributes","cluster-id":"edc6037504b39883","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T17:11:14.669776Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:11:14.670485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:11:14.67084Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T17:11:14.67095Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T17:11:14.67335Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.242:2379"} {"level":"info","ts":"2026-04-13T17:11:14.673974Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T17:11:33.48655Z","caller":"traceutil/trace.go:171","msg":"trace[263237329] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"332.058561ms","start":"2026-04-13T17:11:33.154466Z","end":"2026-04-13T17:11:33.486525Z","steps":["trace[263237329] 'read index received' (duration: 287.431976ms)","trace[263237329] 'applied index is now lower than readState.Index' (duration: 44.625415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:33.486748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.935074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:11:33.486777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.296998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-13T17:11:33.486811Z","caller":"traceutil/trace.go:171","msg":"trace[1943940353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"140.050088ms","start":"2026-04-13T17:11:33.346751Z","end":"2026-04-13T17:11:33.486801Z","steps":["trace[1943940353] 'agreement among raft nodes before linearized reading' (duration: 139.942314ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:33.486814Z","caller":"traceutil/trace.go:171","msg":"trace[473160376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"332.385561ms","start":"2026-04-13T17:11:33.154419Z","end":"2026-04-13T17:11:33.486804Z","steps":["trace[473160376] 'agreement among raft nodes before linearized reading' (duration: 332.299568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.486846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:33.154395Z","time spent":"332.442953ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T17:11:38.892131Z","caller":"traceutil/trace.go:171","msg":"trace[309599755] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"143.736224ms","start":"2026-04-13T17:11:38.748377Z","end":"2026-04-13T17:11:38.892114Z","steps":["trace[309599755] 'process raft request' (duration: 143.619391ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:45.491501Z","caller":"traceutil/trace.go:171","msg":"trace[203981575] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"467.982868ms","start":"2026-04-13T17:11:45.023497Z","end":"2026-04-13T17:11:45.49148Z","steps":["trace[203981575] 'process raft request' (duration: 467.868645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:45.491876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.023471Z","time spent":"468.096842ms","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:11:45.497386Z","caller":"traceutil/trace.go:171","msg":"trace[1436899356] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"462.903329ms","start":"2026-04-13T17:11:45.034465Z","end":"2026-04-13T17:11:45.497369Z","steps":["trace[1436899356] 'process raft request' (duration: 462.717773ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:45.497378Z","caller":"traceutil/trace.go:171","msg":"trace[1338960085] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"470.440746ms","start":"2026-04-13T17:11:45.026907Z","end":"2026-04-13T17:11:45.497347Z","steps":["trace[1338960085] 'process raft request' (duration: 470.166377ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:45.4974Z","caller":"traceutil/trace.go:171","msg":"trace[1576954263] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:514; }","duration":"149.851886ms","start":"2026-04-13T17:11:45.347528Z","end":"2026-04-13T17:11:45.49738Z","steps":["trace[1576954263] 'read index received' (duration: 143.93164ms)","trace[1576954263] 'applied index is now lower than readState.Index' (duration: 5.919486ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:45.497508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.973989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:45.497461Z","caller":"traceutil/trace.go:171","msg":"trace[473736145] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"457.161168ms","start":"2026-04-13T17:11:45.040274Z","end":"2026-04-13T17:11:45.497435Z","steps":["trace[473736145] 'process raft request' (duration: 457.008453ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:45.497538Z","caller":"traceutil/trace.go:171","msg":"trace[348580353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"150.054713ms","start":"2026-04-13T17:11:45.347475Z","end":"2026-04-13T17:11:45.49753Z","steps":["trace[348580353] 'agreement among raft nodes before linearized reading' (duration: 149.97765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:45.497611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.034436Z","time spent":"462.999612ms","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:45.497659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.026892Z","time spent":"470.559079ms","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:45.497617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.040254Z","time spent":"457.292812ms","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:45.497659Z","caller":"traceutil/trace.go:171","msg":"trace[289605713] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"358.131662ms","start":"2026-04-13T17:11:45.139407Z","end":"2026-04-13T17:11:45.497539Z","steps":["trace[289605713] 'process raft request' (duration: 357.920065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:45.497917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.139365Z","time spent":"358.432731ms","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:11:51.819044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.511387ms","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-13T17:11:51.819109Z","caller":"traceutil/trace.go:171","msg":"trace[1892276650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"218.59968ms","start":"2026-04-13T17:11:51.600497Z","end":"2026-04-13T17:11:51.819097Z","steps":["trace[1892276650] 'range keys from in-memory index tree' (duration: 218.371253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:51.819124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.685882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pgxqh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-13T17:11:51.819428Z","caller":"traceutil/trace.go:171","msg":"trace[929283856] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pgxqh; range_end:; response_count:1; response_revision:578; }","duration":"246.065954ms","start":"2026-04-13T17:11:51.573333Z","end":"2026-04-13T17:11:51.819399Z","steps":["trace[929283856] 'range keys from in-memory index tree' (duration: 245.427314ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:52.000195Z","caller":"traceutil/trace.go:171","msg":"trace[1588676269] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"257.319328ms","start":"2026-04-13T17:11:51.74284Z","end":"2026-04-13T17:11:52.000159Z","steps":["trace[1588676269] 'process raft request' (duration: 249.703099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:52.223328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.340953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:11:52.223617Z","caller":"traceutil/trace.go:171","msg":"trace[2053404383] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"163.523866ms","start":"2026-04-13T17:11:52.060073Z","end":"2026-04-13T17:11:52.223597Z","steps":["trace[2053404383] 'process raft request' (duration: 163.457674ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:52.223857Z","caller":"traceutil/trace.go:171","msg":"trace[1006890822] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"401.215337ms","start":"2026-04-13T17:11:51.822587Z","end":"2026-04-13T17:11:52.223802Z","steps":["trace[1006890822] 'process raft request' (duration: 283.171912ms)","trace[1006890822] 'compare' (duration: 117.178788ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:52.223948Z","caller":"traceutil/trace.go:171","msg":"trace[787517681] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"390.856892ms","start":"2026-04-13T17:11:51.833034Z","end":"2026-04-13T17:11:52.223891Z","steps":["trace[787517681] 'process raft request' (duration: 390.404177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:52.224097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:51.82257Z","time spent":"401.393763ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:52.224239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:51.833025Z","time spent":"391.064528ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:53.205669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.649362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:11:53.206018Z","caller":"traceutil/trace.go:171","msg":"trace[15071504] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"860.7227ms","start":"2026-04-13T17:11:52.345249Z","end":"2026-04-13T17:11:53.205972Z","steps":["trace[15071504] 'read index received' (duration: 498.680445ms)","trace[15071504] 'applied index is now lower than readState.Index' (duration: 362.038055ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:53.206189Z","caller":"traceutil/trace.go:171","msg":"trace[137089272] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"964.363011ms","start":"2026-04-13T17:11:52.241502Z","end":"2026-04-13T17:11:53.205865Z","steps":["trace[137089272] 'process raft request' (duration: 602.42974ms)","trace[137089272] 'compare' (duration: 361.25918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:53.20633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:52.241484Z","time spent":"964.778985ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:11:53.21656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.280521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:53.216735Z","caller":"traceutil/trace.go:171","msg":"trace[819439088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"871.533779ms","start":"2026-04-13T17:11:52.34517Z","end":"2026-04-13T17:11:53.216704Z","steps":["trace[819439088] 'agreement among raft nodes before linearized reading' (duration: 861.011808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:53.216788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.970737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T17:11:53.216888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.788988ms","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-13T17:11:53.216962Z","caller":"traceutil/trace.go:171","msg":"trace[1148004879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"267.922172ms","start":"2026-04-13T17:11:52.949022Z","end":"2026-04-13T17:11:53.216945Z","steps":["trace[1148004879] 'agreement among raft nodes before linearized reading' (duration: 267.565521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:53.21697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.535102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T17:11:53.216928Z","caller":"traceutil/trace.go:171","msg":"trace[1206279607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"705.184924ms","start":"2026-04-13T17:11:52.511725Z","end":"2026-04-13T17:11:53.21691Z","steps":["trace[1206279607] 'agreement among raft nodes before linearized reading' (duration: 704.847823ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:53.217069Z","caller":"traceutil/trace.go:171","msg":"trace[251851509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"560.698416ms","start":"2026-04-13T17:11:52.656354Z","end":"2026-04-13T17:11:53.217053Z","steps":["trace[251851509] 'agreement among raft nodes before linearized reading' (duration: 560.242002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:53.21712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:52.511702Z","time spent":"705.37872ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T17:11:53.216858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:52.34514Z","time spent":"871.700955ms","remote":"127.0.0.1:51896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:11:53.217173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:52.656321Z","time spent":"560.82142ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T17:11:54.893897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.39785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:11:54.894249Z","caller":"traceutil/trace.go:171","msg":"trace[1617181348] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"197.577639ms","start":"2026-04-13T17:11:54.696643Z","end":"2026-04-13T17:11:54.894221Z","steps":["trace[1617181348] 'process raft request' (duration: 55.753815ms)","trace[1617181348] 'compare' (duration: 141.263986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:12:08.625793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.575337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:12:08.625906Z","caller":"traceutil/trace.go:171","msg":"trace[1406570961] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"321.324069ms","start":"2026-04-13T17:12:08.304566Z","end":"2026-04-13T17:12:08.62589Z","steps":["trace[1406570961] 'read index received' (duration: 64.192µs)","trace[1406570961] 'applied index is now lower than readState.Index' (duration: 321.258027ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:12:08.625969Z","caller":"traceutil/trace.go:171","msg":"trace[1477939290] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"578.407874ms","start":"2026-04-13T17:12:08.047499Z","end":"2026-04-13T17:12:08.625907Z","steps":["trace[1477939290] 'process raft request' (duration: 183.620511ms)","trace[1477939290] 'compare' (duration: 394.119762ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:12:08.626147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.991962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-13T17:12:08.62614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:08.047439Z","time spent":"578.620151ms","remote":"127.0.0.1:51914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:12:08.626176Z","caller":"traceutil/trace.go:171","msg":"trace[883571992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"129.060974ms","start":"2026-04-13T17:12:08.497106Z","end":"2026-04-13T17:12:08.626167Z","steps":["trace[883571992] 'agreement among raft nodes before linearized reading' (duration: 128.889598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:08.626157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.595848ms","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-13T17:12:08.626233Z","caller":"traceutil/trace.go:171","msg":"trace[96951589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:738; }","duration":"321.725581ms","start":"2026-04-13T17:12:08.304491Z","end":"2026-04-13T17:12:08.626216Z","steps":["trace[96951589] 'agreement among raft nodes before linearized reading' (duration: 321.458133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:08.626276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:08.304469Z","time spent":"321.797774ms","remote":"127.0.0.1:52202","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-13T17:12:08.890461Z","caller":"traceutil/trace.go:171","msg":"trace[1321613392] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"260.021748ms","start":"2026-04-13T17:12:08.630412Z","end":"2026-04-13T17:12:08.890433Z","steps":["trace[1321613392] 'process raft request' (duration: 257.238721ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:08.895234Z","caller":"traceutil/trace.go:171","msg":"trace[1028523168] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"264.734808ms","start":"2026-04-13T17:12:08.630476Z","end":"2026-04-13T17:12:08.895211Z","steps":["trace[1028523168] 'process raft request' (duration: 264.583873ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:08.895377Z","caller":"traceutil/trace.go:171","msg":"trace[1728630582] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:767; }","duration":"263.378125ms","start":"2026-04-13T17:12:08.631966Z","end":"2026-04-13T17:12:08.895344Z","steps":["trace[1728630582] 'read index received' (duration: 255.749734ms)","trace[1728630582] 'applied index is now lower than readState.Index' (duration: 7.626581ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:12:08.895397Z","caller":"traceutil/trace.go:171","msg":"trace[1231250873] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"206.709351ms","start":"2026-04-13T17:12:08.688678Z","end":"2026-04-13T17:12:08.895388Z","steps":["trace[1231250873] 'process raft request' (duration: 206.493405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:08.89548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.498699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-13T17:12:08.89556Z","caller":"traceutil/trace.go:171","msg":"trace[670259680] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:741; }","duration":"263.56031ms","start":"2026-04-13T17:12:08.631943Z","end":"2026-04-13T17:12:08.895503Z","steps":["trace[670259680] 'agreement among raft nodes before linearized reading' (duration: 263.479928ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:35.588506Z","caller":"traceutil/trace.go:171","msg":"trace[1004133492] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"151.260507ms","start":"2026-04-13T17:12:35.43722Z","end":"2026-04-13T17:12:35.588481Z","steps":["trace[1004133492] 'read index received' (duration: 151.079881ms)","trace[1004133492] 'applied index is now lower than readState.Index' (duration: 179.366µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:12:35.588572Z","caller":"traceutil/trace.go:171","msg":"trace[1353492136] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"152.824686ms","start":"2026-04-13T17:12:35.435712Z","end":"2026-04-13T17:12:35.588537Z","steps":["trace[1353492136] 'process raft request' (duration: 152.611789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:35.588857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.225699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T17:12:35.588905Z","caller":"traceutil/trace.go:171","msg":"trace[567922566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1132; }","duration":"135.339723ms","start":"2026-04-13T17:12:35.453553Z","end":"2026-04-13T17:12:35.588893Z","steps":["trace[567922566] 'agreement among raft nodes before linearized reading' (duration: 135.104525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:35.588919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.660069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T17:12:35.589026Z","caller":"traceutil/trace.go:171","msg":"trace[1585843775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1132; }","duration":"151.830904ms","start":"2026-04-13T17:12:35.437171Z","end":"2026-04-13T17:12:35.589002Z","steps":["trace[1585843775] 'agreement among raft nodes before linearized reading' (duration: 151.467393ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:35.911398Z","caller":"traceutil/trace.go:171","msg":"trace[131921575] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"315.785662ms","start":"2026-04-13T17:12:35.59558Z","end":"2026-04-13T17:12:35.911366Z","steps":["trace[131921575] 'process raft request' (duration: 315.558365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:35.911606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:35.595555Z","time spent":"315.935716ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:12:35.91164Z","caller":"traceutil/trace.go:171","msg":"trace[1244041090] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"315.997558ms","start":"2026-04-13T17:12:35.595586Z","end":"2026-04-13T17:12:35.911584Z","steps":["trace[1244041090] 'process raft request' (duration: 315.737699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:35.911836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:35.595561Z","time spent":"316.168483ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:12:51.804573Z","caller":"traceutil/trace.go:171","msg":"trace[1351102669] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1312; }","duration":"144.661588ms","start":"2026-04-13T17:12:51.659892Z","end":"2026-04-13T17:12:51.804553Z","steps":["trace[1351102669] 'read index received' (duration: 144.627637ms)","trace[1351102669] 'applied index is now lower than readState.Index' (duration: 32.901µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:12:51.805263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.34812ms","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-13T17:12:51.805337Z","caller":"traceutil/trace.go:171","msg":"trace[1429373069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"145.447083ms","start":"2026-04-13T17:12:51.659859Z","end":"2026-04-13T17:12:51.805306Z","steps":["trace[1429373069] 'agreement among raft nodes before linearized reading' (duration: 144.778082ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:51.805375Z","caller":"traceutil/trace.go:171","msg":"trace[1902057751] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"201.156576ms","start":"2026-04-13T17:12:51.603505Z","end":"2026-04-13T17:12:51.804662Z","steps":["trace[1902057751] 'process raft request' (duration: 200.942059ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:58.323661Z","caller":"traceutil/trace.go:171","msg":"trace[102432065] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1436; }","duration":"318.058054ms","start":"2026-04-13T17:12:58.005572Z","end":"2026-04-13T17:12:58.32363Z","steps":["trace[102432065] 'read index received' (duration: 317.886698ms)","trace[102432065] 'applied index is now lower than readState.Index' (duration: 170.206µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:12:58.323726Z","caller":"traceutil/trace.go:171","msg":"trace[1915288816] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"337.406746ms","start":"2026-04-13T17:12:57.986298Z","end":"2026-04-13T17:12:58.323705Z","steps":["trace[1915288816] 'process raft request' (duration: 337.150938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:58.323869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:57.986265Z","time spent":"337.52323ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:12:58.323926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.369578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:12:58.324018Z","caller":"traceutil/trace.go:171","msg":"trace[1103798576] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1391; }","duration":"235.505151ms","start":"2026-04-13T17:12:58.088491Z","end":"2026-04-13T17:12:58.323996Z","steps":["trace[1103798576] 'agreement among raft nodes before linearized reading' (duration: 235.355897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:58.324027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.452017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T17:12:58.324097Z","caller":"traceutil/trace.go:171","msg":"trace[314962806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1391; }","duration":"318.55517ms","start":"2026-04-13T17:12:58.005523Z","end":"2026-04-13T17:12:58.324079Z","steps":["trace[314962806] 'agreement among raft nodes before linearized reading' (duration: 318.23212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:58.323946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.3976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T17:12:58.32415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:58.0055Z","time spent":"318.631413ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T17:12:58.324154Z","caller":"traceutil/trace.go:171","msg":"trace[1129589715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1391; }","duration":"315.611287ms","start":"2026-04-13T17:12:58.00853Z","end":"2026-04-13T17:12:58.324142Z","steps":["trace[1129589715] 'agreement among raft nodes before linearized reading' (duration: 315.281767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:58.324202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:58.008487Z","time spent":"315.7042ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T17:13:14.589763Z","caller":"traceutil/trace.go:171","msg":"trace[1648526241] linearizableReadLoop","detail":"{readStateIndex:1748; appliedIndex:1747; }","duration":"207.456513ms","start":"2026-04-13T17:13:14.382284Z","end":"2026-04-13T17:13:14.589741Z","steps":["trace[1648526241] 'read index received' (duration: 207.338529ms)","trace[1648526241] 'applied index is now lower than readState.Index' (duration: 116.824µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:14.589826Z","caller":"traceutil/trace.go:171","msg":"trace[381312526] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"216.364963ms","start":"2026-04-13T17:13:14.373428Z","end":"2026-04-13T17:13:14.589793Z","steps":["trace[381312526] 'process raft request' (duration: 216.165667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:14.589994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.548232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T17:13:14.590034Z","caller":"traceutil/trace.go:171","msg":"trace[641071709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1677; }","duration":"179.635045ms","start":"2026-04-13T17:13:14.41039Z","end":"2026-04-13T17:13:14.590025Z","steps":["trace[641071709] 'agreement among raft nodes before linearized reading' (duration: 179.50159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:14.590039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.754732ms","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-13T17:13:14.590115Z","caller":"traceutil/trace.go:171","msg":"trace[618085738] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1677; }","duration":"207.843285ms","start":"2026-04-13T17:13:14.382253Z","end":"2026-04-13T17:13:14.590096Z","steps":["trace[618085738] 'agreement among raft nodes before linearized reading' (duration: 207.631398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:14.590155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.036396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T17:13:14.590217Z","caller":"traceutil/trace.go:171","msg":"trace[840036050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1677; }","duration":"179.117108ms","start":"2026-04-13T17:13:14.411084Z","end":"2026-04-13T17:13:14.590201Z","steps":["trace[840036050] 'agreement among raft nodes before linearized reading' (duration: 178.942623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.833136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.800278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:13:20.833394Z","caller":"traceutil/trace.go:171","msg":"trace[1418947694] linearizableReadLoop","detail":"{readStateIndex:1857; appliedIndex:1855; }","duration":"448.168323ms","start":"2026-04-13T17:13:20.385201Z","end":"2026-04-13T17:13:20.833369Z","steps":["trace[1418947694] 'read index received' (duration: 357.177463ms)","trace[1418947694] 'applied index is now lower than readState.Index' (duration: 90.98951ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:20.833528Z","caller":"traceutil/trace.go:171","msg":"trace[1222773185] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"738.255951ms","start":"2026-04-13T17:13:20.095257Z","end":"2026-04-13T17:13:20.833513Z","steps":["trace[1222773185] 'process raft request' (duration: 738.021194ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.833767Z","caller":"traceutil/trace.go:171","msg":"trace[332621263] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"792.091759ms","start":"2026-04-13T17:13:20.041642Z","end":"2026-04-13T17:13:20.833734Z","steps":["trace[332621263] 'process raft request' (duration: 293.592069ms)","trace[332621263] 'compare' (duration: 497.20553ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:13:20.833875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.095223Z","time spent":"738.593002ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:13:20.833828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.617428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dd7aa5f3-cf3a-4f67-a74c-e77e7222f5e7\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-13T17:13:20.834133Z","caller":"traceutil/trace.go:171","msg":"trace[597090567] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dd7aa5f3-cf3a-4f67-a74c-e77e7222f5e7; range_end:; response_count:1; response_revision:1784; }","duration":"448.893796ms","start":"2026-04-13T17:13:20.385145Z","end":"2026-04-13T17:13:20.834039Z","steps":["trace[597090567] 'agreement among raft nodes before linearized reading' (duration: 448.592317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.834168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.041623Z","time spent":"792.363157ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:13:20.83423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.385112Z","time spent":"449.088582ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3771,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dd7aa5f3-cf3a-4f67-a74c-e77e7222f5e7\" "} {"level":"info","ts":"2026-04-13T17:13:20.909622Z","caller":"traceutil/trace.go:171","msg":"trace[1921163326] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"299.830136ms","start":"2026-04-13T17:13:20.609764Z","end":"2026-04-13T17:13:20.909594Z","steps":["trace[1921163326] 'process raft request' (duration: 299.561537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.909654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.645327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T17:13:20.909766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.640027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T17:13:20.909816Z","caller":"traceutil/trace.go:171","msg":"trace[1239767265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1785; }","duration":"291.686478ms","start":"2026-04-13T17:13:20.618112Z","end":"2026-04-13T17:13:20.909799Z","steps":["trace[1239767265] 'agreement among raft nodes before linearized reading' (duration: 291.574694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.909809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.320721ms","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-13T17:13:20.909851Z","caller":"traceutil/trace.go:171","msg":"trace[537074448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1785; }","duration":"291.827503ms","start":"2026-04-13T17:13:20.617969Z","end":"2026-04-13T17:13:20.909797Z","steps":["trace[537074448] 'agreement among raft nodes before linearized reading' (duration: 291.568135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.909868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.649241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T17:13:20.909944Z","caller":"traceutil/trace.go:171","msg":"trace[665377712] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1785; }","duration":"287.526017ms","start":"2026-04-13T17:13:20.622378Z","end":"2026-04-13T17:13:20.909904Z","steps":["trace[665377712] 'agreement among raft nodes before linearized reading' (duration: 287.143485ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:20.909962Z","caller":"traceutil/trace.go:171","msg":"trace[2111829858] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1785; }","duration":"163.803925ms","start":"2026-04-13T17:13:20.746136Z","end":"2026-04-13T17:13:20.909939Z","steps":["trace[2111829858] 'agreement among raft nodes before linearized reading' (duration: 163.513396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.909931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.84045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:20.910054Z","caller":"traceutil/trace.go:171","msg":"trace[510714254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1785; }","duration":"367.994994ms","start":"2026-04-13T17:13:20.542032Z","end":"2026-04-13T17:13:20.910027Z","steps":["trace[510714254] 'agreement among raft nodes before linearized reading' (duration: 367.778057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.91012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.542024Z","time spent":"368.085838ms","remote":"127.0.0.1:51896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:13:20.910194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.897274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:20.910258Z","caller":"traceutil/trace.go:171","msg":"trace[254455341] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1785; }","duration":"376.046429ms","start":"2026-04-13T17:13:20.534196Z","end":"2026-04-13T17:13:20.910242Z","steps":["trace[254455341] 'agreement among raft nodes before linearized reading' (duration: 375.288775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:20.910345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.534175Z","time spent":"376.155093ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:13:21.460955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139747488558846787,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:13:21.559666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.278093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:13:21.559964Z","caller":"traceutil/trace.go:171","msg":"trace[844495896] linearizableReadLoop","detail":"{readStateIndex:1861; appliedIndex:1858; }","duration":"599.189386ms","start":"2026-04-13T17:13:20.960754Z","end":"2026-04-13T17:13:21.559943Z","steps":["trace[844495896] 'read index received' (duration: 597.273825ms)","trace[844495896] 'applied index is now lower than readState.Index' (duration: 1.914691ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:21.559976Z","caller":"traceutil/trace.go:171","msg":"trace[1603970106] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"645.158336ms","start":"2026-04-13T17:13:20.914785Z","end":"2026-04-13T17:13:21.559943Z","steps":["trace[1603970106] 'process raft request' (duration: 29.501401ms)","trace[1603970106] 'compare' (duration: 614.741656ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:21.56012Z","caller":"traceutil/trace.go:171","msg":"trace[597588356] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"644.799084ms","start":"2026-04-13T17:13:20.915309Z","end":"2026-04-13T17:13:21.560108Z","steps":["trace[597588356] 'process raft request' (duration: 644.590197ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:21.560134Z","caller":"traceutil/trace.go:171","msg":"trace[1642550811] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"645.270289ms","start":"2026-04-13T17:13:20.914841Z","end":"2026-04-13T17:13:21.560111Z","steps":["trace[1642550811] 'process raft request' (duration: 644.955399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:21.560204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.9153Z","time spent":"644.863677ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:13:21.560235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.914809Z","time spent":"645.384112ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:13:21.560233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.91476Z","time spent":"645.341712ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:13:21.560801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.051773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T17:13:21.560845Z","caller":"traceutil/trace.go:171","msg":"trace[1163890390] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1788; }","duration":"600.131146ms","start":"2026-04-13T17:13:20.960702Z","end":"2026-04-13T17:13:21.560833Z","steps":["trace[1163890390] 'agreement among raft nodes before linearized reading' (duration: 599.980141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:21.560847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.768122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:13:21.560878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:20.960682Z","time spent":"600.189808ms","remote":"127.0.0.1:33854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-13T17:13:21.560894Z","caller":"traceutil/trace.go:171","msg":"trace[260027887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1788; }","duration":"213.838243ms","start":"2026-04-13T17:13:21.347043Z","end":"2026-04-13T17:13:21.560882Z","steps":["trace[260027887] 'agreement among raft nodes before linearized reading' (duration: 213.766411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:21.560944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.471661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-13T17:13:21.561035Z","caller":"traceutil/trace.go:171","msg":"trace[948633327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1788; }","duration":"297.647646ms","start":"2026-04-13T17:13:21.263361Z","end":"2026-04-13T17:13:21.561009Z","steps":["trace[948633327] 'agreement among raft nodes before linearized reading' (duration: 297.408759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:21.561118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.823591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-13T17:13:21.561229Z","caller":"traceutil/trace.go:171","msg":"trace[1501432073] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1788; }","duration":"283.948035ms","start":"2026-04-13T17:13:21.277263Z","end":"2026-04-13T17:13:21.561211Z","steps":["trace[1501432073] 'agreement among raft nodes before linearized reading' (duration: 283.582793ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:29.439754Z","caller":"traceutil/trace.go:171","msg":"trace[1716591778] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"145.255521ms","start":"2026-04-13T17:13:29.294455Z","end":"2026-04-13T17:13:29.439711Z","steps":["trace[1716591778] 'process raft request' (duration: 145.073055ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:35.43638Z","caller":"traceutil/trace.go:171","msg":"trace[1433379885] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"546.36869ms","start":"2026-04-13T17:13:34.889983Z","end":"2026-04-13T17:13:35.436352Z","steps":["trace[1433379885] 'process raft request' (duration: 546.203685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:35.436574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:34.889957Z","time spent":"546.525515ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:13:35.437101Z","caller":"traceutil/trace.go:171","msg":"trace[1232238971] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"478.874931ms","start":"2026-04-13T17:13:34.958208Z","end":"2026-04-13T17:13:35.437083Z","steps":["trace[1232238971] 'process raft request' (duration: 478.726906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:35.4372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:13:34.958182Z","time spent":"478.963974ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:14:05.776393Z","caller":"traceutil/trace.go:171","msg":"trace[1225207133] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"232.694883ms","start":"2026-04-13T17:14:05.543659Z","end":"2026-04-13T17:14:05.776354Z","steps":["trace[1225207133] 'process raft request' (duration: 229.781341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:11.996875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.022504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:14:11.997091Z","caller":"traceutil/trace.go:171","msg":"trace[570313510] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"169.435174ms","start":"2026-04-13T17:14:11.82762Z","end":"2026-04-13T17:14:11.997055Z","steps":["trace[570313510] 'process raft request' (duration: 54.142351ms)","trace[570313510] 'compare' (duration: 114.922681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:14:12.862584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.912947ms","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-13T17:14:12.862718Z","caller":"traceutil/trace.go:171","msg":"trace[191366251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2121; }","duration":"507.029431ms","start":"2026-04-13T17:14:12.355631Z","end":"2026-04-13T17:14:12.862661Z","steps":["trace[191366251] 'range keys from in-memory index tree' (duration: 506.69368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:12.862779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:12.355596Z","time spent":"507.167385ms","remote":"127.0.0.1:52202","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-13T17:14:12.86288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.382965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:14:12.862919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.494251ms","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-13T17:14:12.862984Z","caller":"traceutil/trace.go:171","msg":"trace[22721519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2121; }","duration":"515.515019ms","start":"2026-04-13T17:14:12.347447Z","end":"2026-04-13T17:14:12.862962Z","steps":["trace[22721519] 'range keys from in-memory index tree' (duration: 515.249361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:12.863028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:12.347436Z","time spent":"515.582081ms","remote":"127.0.0.1:51896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T17:14:12.863023Z","caller":"traceutil/trace.go:171","msg":"trace[423417812] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2121; }","duration":"529.638195ms","start":"2026-04-13T17:14:12.333359Z","end":"2026-04-13T17:14:12.862997Z","steps":["trace[423417812] 'range keys from in-memory index tree' (duration: 529.262244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:12.86309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:12.333341Z","time spent":"529.735029ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-13T17:14:13.778448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.514721ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-13T17:14:13.778991Z","caller":"traceutil/trace.go:171","msg":"trace[1767633388] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"163.892489ms","start":"2026-04-13T17:14:13.615059Z","end":"2026-04-13T17:14:13.778952Z","steps":["trace[1767633388] 'process raft request' (duration: 163.494516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.216775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.047543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:14:14.21687Z","caller":"traceutil/trace.go:171","msg":"trace[440584147] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2209; }","duration":"433.303212ms","start":"2026-04-13T17:14:13.78355Z","end":"2026-04-13T17:14:14.216853Z","steps":["trace[440584147] 'read index received' (duration: 276.051933ms)","trace[440584147] 'applied index is now lower than readState.Index' (duration: 157.249889ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:14.216945Z","caller":"traceutil/trace.go:171","msg":"trace[344579805] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"560.122379ms","start":"2026-04-13T17:14:13.656809Z","end":"2026-04-13T17:14:14.216931Z","steps":["trace[344579805] 'process raft request' (duration: 402.817699ms)","trace[344579805] 'compare' (duration: 156.712942ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:14:14.216999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:13.656789Z","time spent":"560.185301ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:14:14.217204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.658893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:14:14.217232Z","caller":"traceutil/trace.go:171","msg":"trace[1628344921] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2125; }","duration":"433.726595ms","start":"2026-04-13T17:14:13.783497Z","end":"2026-04-13T17:14:14.217224Z","steps":["trace[1628344921] 'agreement among raft nodes before linearized reading' (duration: 433.667843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.217257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:13.783476Z","time spent":"433.775006ms","remote":"127.0.0.1:33702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"info","ts":"2026-04-13T17:14:14.220287Z","caller":"traceutil/trace.go:171","msg":"trace[1701282635] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"392.563285ms","start":"2026-04-13T17:14:13.827702Z","end":"2026-04-13T17:14:14.220265Z","steps":["trace[1701282635] 'process raft request' (duration: 392.382329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.220685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.24027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-04-13T17:14:14.220854Z","caller":"traceutil/trace.go:171","msg":"trace[1129106830] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2127; }","duration":"327.441217ms","start":"2026-04-13T17:14:13.893398Z","end":"2026-04-13T17:14:14.220839Z","steps":["trace[1129106830] 'agreement among raft nodes before linearized reading' (duration: 327.017583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.22092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:13.893382Z","time spent":"327.526399ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-13T17:14:14.22097Z","caller":"traceutil/trace.go:171","msg":"trace[1613744989] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"207.238239ms","start":"2026-04-13T17:14:14.013696Z","end":"2026-04-13T17:14:14.220934Z","steps":["trace[1613744989] 'process raft request' (duration: 206.526356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.221009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.964508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T17:14:14.220875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:13.827674Z","time spent":"392.687768ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:14:14.221076Z","caller":"traceutil/trace.go:171","msg":"trace[681384215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2127; }","duration":"219.078193ms","start":"2026-04-13T17:14:14.001981Z","end":"2026-04-13T17:14:14.22106Z","steps":["trace[681384215] 'agreement among raft nodes before linearized reading' (duration: 218.905577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.221009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.505787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T17:14:14.221116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.492514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a5f9edabaea651\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-04-13T17:14:14.221109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.435216ms","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-13T17:14:14.221177Z","caller":"traceutil/trace.go:171","msg":"trace[939490290] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a5f9edabaea651; range_end:; response_count:1; response_revision:2127; }","duration":"243.596068ms","start":"2026-04-13T17:14:13.977563Z","end":"2026-04-13T17:14:14.221159Z","steps":["trace[939490290] 'agreement among raft nodes before linearized reading' (duration: 243.450573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.221191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.151936ms","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-13T17:14:14.221217Z","caller":"traceutil/trace.go:171","msg":"trace[359226532] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2127; }","duration":"188.358422ms","start":"2026-04-13T17:14:14.032849Z","end":"2026-04-13T17:14:14.221207Z","steps":["trace[359226532] 'agreement among raft nodes before linearized reading' (duration: 188.303281ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:14.221207Z","caller":"traceutil/trace.go:171","msg":"trace[860391656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2127; }","duration":"189.592461ms","start":"2026-04-13T17:14:14.031591Z","end":"2026-04-13T17:14:14.221183Z","steps":["trace[860391656] 'agreement among raft nodes before linearized reading' (duration: 189.25496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.22124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.563862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T17:14:14.221223Z","caller":"traceutil/trace.go:171","msg":"trace[998907439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2127; }","duration":"188.717323ms","start":"2026-04-13T17:14:14.03242Z","end":"2026-04-13T17:14:14.221138Z","steps":["trace[998907439] 'agreement among raft nodes before linearized reading' (duration: 188.117384ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:14.221304Z","caller":"traceutil/trace.go:171","msg":"trace[1200698657] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2127; }","duration":"178.663846ms","start":"2026-04-13T17:14:14.042623Z","end":"2026-04-13T17:14:14.221287Z","steps":["trace[1200698657] 'agreement among raft nodes before linearized reading' (duration: 178.501791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.221556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.724635ms","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-13T17:14:14.221598Z","caller":"traceutil/trace.go:171","msg":"trace[317164421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2127; }","duration":"189.87396ms","start":"2026-04-13T17:14:14.031713Z","end":"2026-04-13T17:14:14.221587Z","steps":["trace[317164421] 'agreement among raft nodes before linearized reading' (duration: 189.134127ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:14.449733Z","caller":"traceutil/trace.go:171","msg":"trace[1380180264] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"224.655108ms","start":"2026-04-13T17:14:14.225045Z","end":"2026-04-13T17:14:14.4497Z","steps":["trace[1380180264] 'process raft request' (duration: 224.291837ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:14.450069Z","caller":"traceutil/trace.go:171","msg":"trace[1841356100] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"223.037728ms","start":"2026-04-13T17:14:14.226994Z","end":"2026-04-13T17:14:14.450031Z","steps":["trace[1841356100] 'process raft request' (duration: 222.592294ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:14.450197Z","caller":"traceutil/trace.go:171","msg":"trace[1272323065] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2212; }","duration":"215.916712ms","start":"2026-04-13T17:14:14.234253Z","end":"2026-04-13T17:14:14.450169Z","steps":["trace[1272323065] 'read index received' (duration: 63.30169ms)","trace[1272323065] 'applied index is now lower than readState.Index' (duration: 152.613982ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:14.450268Z","caller":"traceutil/trace.go:171","msg":"trace[649922335] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"222.937895ms","start":"2026-04-13T17:14:14.227282Z","end":"2026-04-13T17:14:14.45022Z","steps":["trace[649922335] 'process raft request' (duration: 222.766119ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:14.45034Z","caller":"traceutil/trace.go:171","msg":"trace[142060925] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"222.806101ms","start":"2026-04-13T17:14:14.227504Z","end":"2026-04-13T17:14:14.45031Z","steps":["trace[142060925] 'process raft request' (duration: 222.594614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.450377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.105699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:14:14.45049Z","caller":"traceutil/trace.go:171","msg":"trace[1165716741] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2133; }","duration":"216.257503ms","start":"2026-04-13T17:14:14.234206Z","end":"2026-04-13T17:14:14.450464Z","steps":["trace[1165716741] 'agreement among raft nodes before linearized reading' (duration: 216.111359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:14.450531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.423586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:14:14.450576Z","caller":"traceutil/trace.go:171","msg":"trace[922936004] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"223.457641ms","start":"2026-04-13T17:14:14.227073Z","end":"2026-04-13T17:14:14.45053Z","steps":["trace[922936004] 'process raft request' (duration: 222.825851ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:14.450657Z","caller":"traceutil/trace.go:171","msg":"trace[1331536254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2133; }","duration":"102.602022ms","start":"2026-04-13T17:14:14.347979Z","end":"2026-04-13T17:14:14.450581Z","steps":["trace[1331536254] 'agreement among raft nodes before linearized reading' (duration: 102.467378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:07.645311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.922984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:15:07.645417Z","caller":"traceutil/trace.go:171","msg":"trace[699399972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2585; }","duration":"298.073748ms","start":"2026-04-13T17:15:07.34732Z","end":"2026-04-13T17:15:07.645394Z","steps":["trace[699399972] 'range keys from in-memory index tree' (duration: 297.855531ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:42.220926Z","caller":"traceutil/trace.go:171","msg":"trace[465503819] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"112.356837ms","start":"2026-04-13T17:15:42.108543Z","end":"2026-04-13T17:15:42.2209Z","steps":["trace[465503819] 'process raft request' (duration: 112.088328ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:42.499767Z","caller":"traceutil/trace.go:171","msg":"trace[476914638] linearizableReadLoop","detail":"{readStateIndex:2904; appliedIndex:2903; }","duration":"153.46358ms","start":"2026-04-13T17:15:42.346281Z","end":"2026-04-13T17:15:42.499744Z","steps":["trace[476914638] 'read index received' (duration: 153.237293ms)","trace[476914638] 'applied index is now lower than readState.Index' (duration: 221.887µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:15:42.499793Z","caller":"traceutil/trace.go:171","msg":"trace[854187029] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"195.198294ms","start":"2026-04-13T17:15:42.304571Z","end":"2026-04-13T17:15:42.49977Z","steps":["trace[854187029] 'process raft request' (duration: 195.011258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:42.499883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.594935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:15:42.499916Z","caller":"traceutil/trace.go:171","msg":"trace[686104601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2793; }","duration":"153.680317ms","start":"2026-04-13T17:15:42.346224Z","end":"2026-04-13T17:15:42.499905Z","steps":["trace[686104601] 'agreement among raft nodes before linearized reading' (duration: 153.612835ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:17:31.636928Z","caller":"traceutil/trace.go:171","msg":"trace[378159070] transaction","detail":"{read_only:false; response_revision:3493; number_of_response:1; }","duration":"185.454838ms","start":"2026-04-13T17:17:31.451445Z","end":"2026-04-13T17:17:31.6369Z","steps":["trace[378159070] 'process raft request' (duration: 185.315764ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:17:31.63969Z","caller":"traceutil/trace.go:171","msg":"trace[599470172] transaction","detail":"{read_only:false; response_revision:3495; number_of_response:1; }","duration":"187.541074ms","start":"2026-04-13T17:17:31.452117Z","end":"2026-04-13T17:17:31.639659Z","steps":["trace[599470172] 'process raft request' (duration: 187.448091ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:17:31.639757Z","caller":"traceutil/trace.go:171","msg":"trace[526504610] linearizableReadLoop","detail":"{readStateIndex:3636; appliedIndex:3632; }","duration":"147.200811ms","start":"2026-04-13T17:17:31.492524Z","end":"2026-04-13T17:17:31.639725Z","steps":["trace[526504610] 'read index received' (duration: 144.348261ms)","trace[526504610] 'applied index is now lower than readState.Index' (duration: 2.85041ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:17:31.639791Z","caller":"traceutil/trace.go:171","msg":"trace[893485565] transaction","detail":"{read_only:false; response_revision:3496; number_of_response:1; }","duration":"186.14643ms","start":"2026-04-13T17:17:31.453618Z","end":"2026-04-13T17:17:31.639764Z","steps":["trace[893485565] 'process raft request' (duration: 185.989545ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:17:31.639706Z","caller":"traceutil/trace.go:171","msg":"trace[1357034539] transaction","detail":"{read_only:false; response_revision:3494; number_of_response:1; }","duration":"187.629517ms","start":"2026-04-13T17:17:31.452039Z","end":"2026-04-13T17:17:31.639668Z","steps":["trace[1357034539] 'process raft request' (duration: 187.379589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:17:31.639988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.441709ms","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-13T17:17:31.640061Z","caller":"traceutil/trace.go:171","msg":"trace[1429198627] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3496; }","duration":"147.548592ms","start":"2026-04-13T17:17:31.492492Z","end":"2026-04-13T17:17:31.64004Z","steps":["trace[1429198627] 'agreement among raft nodes before linearized reading' (duration: 147.355876ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:08.346702Z","caller":"traceutil/trace.go:171","msg":"trace[639083505] transaction","detail":"{read_only:false; response_revision:3766; number_of_response:1; }","duration":"222.412053ms","start":"2026-04-13T17:18:08.124228Z","end":"2026-04-13T17:18:08.34664Z","steps":["trace[639083505] 'process raft request' (duration: 222.187055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:23.020253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.486084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-13T17:19:23.020353Z","caller":"traceutil/trace.go:171","msg":"trace[2045029274] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4664; }","duration":"134.613937ms","start":"2026-04-13T17:19:22.885718Z","end":"2026-04-13T17:19:23.020332Z","steps":["trace[2045029274] 'range keys from in-memory index tree' (duration: 134.336418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:23.020692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.041465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:19:23.020745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.781391ms","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-13T17:19:23.020797Z","caller":"traceutil/trace.go:171","msg":"trace[1219985256] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:0; response_revision:4664; }","duration":"125.18263ms","start":"2026-04-13T17:19:22.895581Z","end":"2026-04-13T17:19:23.020764Z","steps":["trace[1219985256] 'range keys from in-memory index tree' (duration: 124.962783ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:23.020826Z","caller":"traceutil/trace.go:171","msg":"trace[170166835] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4664; }","duration":"126.903644ms","start":"2026-04-13T17:19:22.893903Z","end":"2026-04-13T17:19:23.020806Z","steps":["trace[170166835] 'count revisions from in-memory index tree' (duration: 126.664136ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:23.97879Z","caller":"traceutil/trace.go:171","msg":"trace[1893872077] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"150.275031ms","start":"2026-04-13T17:19:23.828494Z","end":"2026-04-13T17:19:23.978769Z","steps":["trace[1893872077] 'process raft request' (duration: 150.125177ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:29.363172Z","caller":"traceutil/trace.go:171","msg":"trace[336112989] linearizableReadLoop","detail":"{readStateIndex:5011; appliedIndex:5010; }","duration":"170.555711ms","start":"2026-04-13T17:19:29.192597Z","end":"2026-04-13T17:19:29.363153Z","steps":["trace[336112989] 'read index received' (duration: 170.505539ms)","trace[336112989] 'applied index is now lower than readState.Index' (duration: 48.832µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:29.363265Z","caller":"traceutil/trace.go:171","msg":"trace[69433276] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"247.810579ms","start":"2026-04-13T17:19:29.115403Z","end":"2026-04-13T17:19:29.363213Z","steps":["trace[69433276] 'process raft request' (duration: 247.642113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:29.363398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.187787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rgdx4\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-13T17:19:29.363429Z","caller":"traceutil/trace.go:171","msg":"trace[34538302] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rgdx4; range_end:; response_count:1; response_revision:4823; }","duration":"123.272929ms","start":"2026-04-13T17:19:29.240148Z","end":"2026-04-13T17:19:29.363421Z","steps":["trace[34538302] 'agreement among raft nodes before linearized reading' (duration: 123.126205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:29.364706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.09509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196043"} {"level":"info","ts":"2026-04-13T17:19:29.364795Z","caller":"traceutil/trace.go:171","msg":"trace[34406959] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4823; }","duration":"172.220633ms","start":"2026-04-13T17:19:29.192557Z","end":"2026-04-13T17:19:29.364777Z","steps":["trace[34406959] 'agreement among raft nodes before linearized reading' (duration: 170.675254ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:29.701094Z","caller":"traceutil/trace.go:171","msg":"trace[1745634268] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"252.045172ms","start":"2026-04-13T17:19:29.449017Z","end":"2026-04-13T17:19:29.701062Z","steps":["trace[1745634268] 'process raft request' (duration: 249.452771ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:29.70118Z","caller":"traceutil/trace.go:171","msg":"trace[314558756] linearizableReadLoop","detail":"{readStateIndex:5019; appliedIndex:5012; }","duration":"234.335283ms","start":"2026-04-13T17:19:29.466823Z","end":"2026-04-13T17:19:29.701158Z","steps":["trace[314558756] 'read index received' (duration: 42.609564ms)","trace[314558756] 'applied index is now lower than readState.Index' (duration: 191.724249ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:29.701286Z","caller":"traceutil/trace.go:171","msg":"trace[283136393] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"250.527965ms","start":"2026-04-13T17:19:29.45075Z","end":"2026-04-13T17:19:29.701278Z","steps":["trace[283136393] 'process raft request' (duration: 250.246066ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:29.701326Z","caller":"traceutil/trace.go:171","msg":"trace[1024962397] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"246.349563ms","start":"2026-04-13T17:19:29.45496Z","end":"2026-04-13T17:19:29.70131Z","steps":["trace[1024962397] 'process raft request' (duration: 246.097985ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:29.701322Z","caller":"traceutil/trace.go:171","msg":"trace[281608019] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"243.002067ms","start":"2026-04-13T17:19:29.458293Z","end":"2026-04-13T17:19:29.701295Z","steps":["trace[281608019] 'process raft request' (duration: 242.831791ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:29.701438Z","caller":"traceutil/trace.go:171","msg":"trace[379151940] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"252.129685ms","start":"2026-04-13T17:19:29.449282Z","end":"2026-04-13T17:19:29.701412Z","steps":["trace[379151940] 'process raft request' (duration: 251.62682ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:29.701639Z","caller":"traceutil/trace.go:171","msg":"trace[825593978] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"244.833134ms","start":"2026-04-13T17:19:29.456766Z","end":"2026-04-13T17:19:29.701599Z","steps":["trace[825593978] 'process raft request' (duration: 244.326528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:29.701681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.84392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-13T17:19:29.701738Z","caller":"traceutil/trace.go:171","msg":"trace[804926929] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4831; }","duration":"234.908692ms","start":"2026-04-13T17:19:29.466812Z","end":"2026-04-13T17:19:29.701721Z","steps":["trace[804926929] 'agreement among raft nodes before linearized reading' (duration: 234.786408ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:30.372092Z","caller":"traceutil/trace.go:171","msg":"trace[462903329] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5112; }","duration":"136.91005ms","start":"2026-04-13T17:19:30.235152Z","end":"2026-04-13T17:19:30.372062Z","steps":["trace[462903329] 'read index received' (duration: 136.706563ms)","trace[462903329] 'applied index is now lower than readState.Index' (duration: 202.057µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:30.372146Z","caller":"traceutil/trace.go:171","msg":"trace[1737088939] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"148.074372ms","start":"2026-04-13T17:19:30.224036Z","end":"2026-04-13T17:19:30.372111Z","steps":["trace[1737088939] 'process raft request' (duration: 147.815674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:30.372423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.221489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T17:19:30.372468Z","caller":"traceutil/trace.go:171","msg":"trace[1046232357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4923; }","duration":"137.321593ms","start":"2026-04-13T17:19:30.235134Z","end":"2026-04-13T17:19:30.372456Z","steps":["trace[1046232357] 'agreement among raft nodes before linearized reading' (duration: 137.128107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:30.372478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.286644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-13T17:19:30.372561Z","caller":"traceutil/trace.go:171","msg":"trace[566727792] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4923; }","duration":"106.381317ms","start":"2026-04-13T17:19:30.26616Z","end":"2026-04-13T17:19:30.372541Z","steps":["trace[566727792] 'agreement among raft nodes before linearized reading' (duration: 106.17364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:30.373142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.379347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719288"} {"level":"info","ts":"2026-04-13T17:19:30.3732Z","caller":"traceutil/trace.go:171","msg":"trace[1714867530] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4923; }","duration":"100.46053ms","start":"2026-04-13T17:19:30.272724Z","end":"2026-04-13T17:19:30.373184Z","steps":["trace[1714867530] 'agreement among raft nodes before linearized reading' (duration: 99.994705ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:30.480271Z","caller":"traceutil/trace.go:171","msg":"trace[633309944] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"102.635258ms","start":"2026-04-13T17:19:30.377608Z","end":"2026-04-13T17:19:30.480243Z","steps":["trace[633309944] 'process raft request' (duration: 101.498352ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:30.480355Z","caller":"traceutil/trace.go:171","msg":"trace[1556815951] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"102.605228ms","start":"2026-04-13T17:19:30.377721Z","end":"2026-04-13T17:19:30.480327Z","steps":["trace[1556815951] 'process raft request' (duration: 102.430982ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:30.480468Z","caller":"traceutil/trace.go:171","msg":"trace[862702633] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"102.612598ms","start":"2026-04-13T17:19:30.37782Z","end":"2026-04-13T17:19:30.480433Z","steps":["trace[862702633] 'process raft request' (duration: 102.392941ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:31.73032Z","caller":"traceutil/trace.go:171","msg":"trace[318224437] linearizableReadLoop","detail":"{readStateIndex:5134; appliedIndex:5133; }","duration":"139.022606ms","start":"2026-04-13T17:19:31.591274Z","end":"2026-04-13T17:19:31.730296Z","steps":["trace[318224437] 'read index received' (duration: 138.918383ms)","trace[318224437] 'applied index is now lower than readState.Index' (duration: 102.913µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:31.730436Z","caller":"traceutil/trace.go:171","msg":"trace[848047273] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"215.368505ms","start":"2026-04-13T17:19:31.515023Z","end":"2026-04-13T17:19:31.730391Z","steps":["trace[848047273] 'process raft request' (duration: 215.105097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:31.731024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.728429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719288"} {"level":"info","ts":"2026-04-13T17:19:31.73111Z","caller":"traceutil/trace.go:171","msg":"trace[1474915725] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4944; }","duration":"139.862493ms","start":"2026-04-13T17:19:31.591223Z","end":"2026-04-13T17:19:31.731086Z","steps":["trace[1474915725] 'agreement among raft nodes before linearized reading' (duration: 139.172881ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:35.870784Z","caller":"traceutil/trace.go:171","msg":"trace[466268541] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"129.216767ms","start":"2026-04-13T17:19:35.741539Z","end":"2026-04-13T17:19:35.870756Z","steps":["trace[466268541] 'process raft request' (duration: 123.975561ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:35.876245Z","caller":"traceutil/trace.go:171","msg":"trace[1172387398] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"123.771605ms","start":"2026-04-13T17:19:35.752452Z","end":"2026-04-13T17:19:35.876224Z","steps":["trace[1172387398] 'process raft request' (duration: 123.719873ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:35.876316Z","caller":"traceutil/trace.go:171","msg":"trace[1694477352] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"123.817416ms","start":"2026-04-13T17:19:35.752443Z","end":"2026-04-13T17:19:35.876261Z","steps":["trace[1694477352] 'process raft request' (duration: 123.61831ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:36.067748Z","caller":"traceutil/trace.go:171","msg":"trace[2072492206] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"162.476346ms","start":"2026-04-13T17:19:35.905236Z","end":"2026-04-13T17:19:36.067713Z","steps":["trace[2072492206] 'process raft request' (duration: 162.28256ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:36.253722Z","caller":"traceutil/trace.go:171","msg":"trace[610847168] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"138.582742ms","start":"2026-04-13T17:19:36.115106Z","end":"2026-04-13T17:19:36.253688Z","steps":["trace[610847168] 'process raft request' (duration: 61.198431ms)","trace[610847168] 'compare' (duration: 77.216176ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:36.255597Z","caller":"traceutil/trace.go:171","msg":"trace[1357298644] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"140.282696ms","start":"2026-04-13T17:19:36.115299Z","end":"2026-04-13T17:19:36.255581Z","steps":["trace[1357298644] 'process raft request' (duration: 140.135672ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:36.255904Z","caller":"traceutil/trace.go:171","msg":"trace[258459261] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"139.064767ms","start":"2026-04-13T17:19:36.116827Z","end":"2026-04-13T17:19:36.255892Z","steps":["trace[258459261] 'process raft request' (duration: 138.698915ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:36.255995Z","caller":"traceutil/trace.go:171","msg":"trace[1457419330] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"139.031246ms","start":"2026-04-13T17:19:36.116934Z","end":"2026-04-13T17:19:36.255965Z","steps":["trace[1457419330] 'process raft request' (duration: 138.790969ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:36.256266Z","caller":"traceutil/trace.go:171","msg":"trace[621732112] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"132.48636ms","start":"2026-04-13T17:19:36.123758Z","end":"2026-04-13T17:19:36.256245Z","steps":["trace[621732112] 'process raft request' (duration: 132.078327ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:42.906771Z","caller":"traceutil/trace.go:171","msg":"trace[44422559] transaction","detail":"{read_only:false; response_revision:5173; number_of_response:1; }","duration":"254.682066ms","start":"2026-04-13T17:19:42.652069Z","end":"2026-04-13T17:19:42.906751Z","steps":["trace[44422559] 'process raft request' (duration: 254.458439ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:43.171405Z","caller":"traceutil/trace.go:171","msg":"trace[636463324] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"108.351859ms","start":"2026-04-13T17:19:43.063009Z","end":"2026-04-13T17:19:43.171361Z","steps":["trace[636463324] 'process raft request' (duration: 86.874851ms)","trace[636463324] 'compare' (duration: 21.24827ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:49.716299Z","caller":"traceutil/trace.go:171","msg":"trace[1253676685] linearizableReadLoop","detail":"{readStateIndex:5441; appliedIndex:5440; }","duration":"163.605972ms","start":"2026-04-13T17:19:49.552667Z","end":"2026-04-13T17:19:49.716273Z","steps":["trace[1253676685] 'read index received' (duration: 163.471367ms)","trace[1253676685] 'applied index is now lower than readState.Index' (duration: 133.325µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:19:49.716508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.772373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:19:49.716452Z","caller":"traceutil/trace.go:171","msg":"trace[1940471856] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"330.184098ms","start":"2026-04-13T17:19:49.38622Z","end":"2026-04-13T17:19:49.716404Z","steps":["trace[1940471856] 'process raft request' (duration: 329.842587ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:49.716557Z","caller":"traceutil/trace.go:171","msg":"trace[66843072] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5242; }","duration":"160.870435ms","start":"2026-04-13T17:19:49.555674Z","end":"2026-04-13T17:19:49.716544Z","steps":["trace[66843072] 'agreement among raft nodes before linearized reading' (duration: 160.768122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:49.716689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.718713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:19:49.716696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.003915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rgdx4\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-13T17:19:49.716751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:19:49.386197Z","time spent":"330.383244ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11652,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:19:49.716789Z","caller":"traceutil/trace.go:171","msg":"trace[1445983624] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:5242; }","duration":"142.828486ms","start":"2026-04-13T17:19:49.573937Z","end":"2026-04-13T17:19:49.716766Z","steps":["trace[1445983624] 'agreement among raft nodes before linearized reading' (duration: 142.63179ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:49.716809Z","caller":"traceutil/trace.go:171","msg":"trace[2136145336] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rgdx4; range_end:; response_count:1; response_revision:5242; }","duration":"164.17576ms","start":"2026-04-13T17:19:49.552607Z","end":"2026-04-13T17:19:49.716783Z","steps":["trace[2136145336] 'agreement among raft nodes before linearized reading' (duration: 163.813308ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:51.16232Z","caller":"traceutil/trace.go:171","msg":"trace[1766347267] linearizableReadLoop","detail":"{readStateIndex:5512; appliedIndex:5511; }","duration":"103.824145ms","start":"2026-04-13T17:19:51.058473Z","end":"2026-04-13T17:19:51.162298Z","steps":["trace[1766347267] 'read index received' (duration: 103.633789ms)","trace[1766347267] 'applied index is now lower than readState.Index' (duration: 189.496µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:51.162596Z","caller":"traceutil/trace.go:171","msg":"trace[771803858] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"173.015719ms","start":"2026-04-13T17:19:50.989548Z","end":"2026-04-13T17:19:51.162564Z","steps":["trace[771803858] 'process raft request' (duration: 172.615416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:51.162795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.178587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0cf7f61a-2ff2-4d65-9a87-28086b4587da\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-13T17:19:51.162928Z","caller":"traceutil/trace.go:171","msg":"trace[254970154] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0cf7f61a-2ff2-4d65-9a87-28086b4587da; range_end:; response_count:1; response_revision:5313; }","duration":"104.483087ms","start":"2026-04-13T17:19:51.058426Z","end":"2026-04-13T17:19:51.162909Z","steps":["trace[254970154] 'agreement among raft nodes before linearized reading' (duration: 104.074154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:51.380425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.812299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:19:51.380639Z","caller":"traceutil/trace.go:171","msg":"trace[1718182348] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"210.983967ms","start":"2026-04-13T17:19:51.169629Z","end":"2026-04-13T17:19:51.380613Z","steps":["trace[1718182348] 'process raft request' (duration: 78.879618ms)","trace[1718182348] 'compare' (duration: 131.659875ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:52.200904Z","caller":"traceutil/trace.go:171","msg":"trace[1007830058] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"278.045932ms","start":"2026-04-13T17:19:51.922827Z","end":"2026-04-13T17:19:52.200873Z","steps":["trace[1007830058] 'process raft request' (duration: 277.845526ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:52.21501Z","caller":"traceutil/trace.go:171","msg":"trace[1459230199] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"282.977369ms","start":"2026-04-13T17:19:51.932019Z","end":"2026-04-13T17:19:52.214996Z","steps":["trace[1459230199] 'process raft request' (duration: 282.875526ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:52.21514Z","caller":"traceutil/trace.go:171","msg":"trace[1948471961] linearizableReadLoop","detail":"{readStateIndex:5516; appliedIndex:5513; }","duration":"170.887982ms","start":"2026-04-13T17:19:52.044243Z","end":"2026-04-13T17:19:52.215132Z","steps":["trace[1948471961] 'read index received' (duration: 156.467937ms)","trace[1948471961] 'applied index is now lower than readState.Index' (duration: 14.417035ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:19:52.215254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.002795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:19:52.215287Z","caller":"traceutil/trace.go:171","msg":"trace[2050489535] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:5317; }","duration":"171.101549ms","start":"2026-04-13T17:19:52.044177Z","end":"2026-04-13T17:19:52.215279Z","steps":["trace[2050489535] 'agreement among raft nodes before linearized reading' (duration: 171.019646ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:52.215253Z","caller":"traceutil/trace.go:171","msg":"trace[548897183] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"277.855137ms","start":"2026-04-13T17:19:51.937359Z","end":"2026-04-13T17:19:52.215214Z","steps":["trace[548897183] 'process raft request' (duration: 277.605449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:52.215501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.51486ms","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-13T17:19:52.215584Z","caller":"traceutil/trace.go:171","msg":"trace[1473139317] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5317; }","duration":"170.663105ms","start":"2026-04-13T17:19:52.044901Z","end":"2026-04-13T17:19:52.215564Z","steps":["trace[1473139317] 'agreement among raft nodes before linearized reading' (duration: 170.396477ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:54.498425Z","caller":"traceutil/trace.go:171","msg":"trace[106629796] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"292.86158ms","start":"2026-04-13T17:19:54.205533Z","end":"2026-04-13T17:19:54.498395Z","steps":["trace[106629796] 'process raft request' (duration: 292.556161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:54.776345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.40185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:19:54.776537Z","caller":"traceutil/trace.go:171","msg":"trace[1859452368] linearizableReadLoop","detail":"{readStateIndex:5569; appliedIndex:5566; }","duration":"552.253555ms","start":"2026-04-13T17:19:54.224265Z","end":"2026-04-13T17:19:54.776518Z","steps":["trace[1859452368] 'read index received' (duration: 273.749407ms)","trace[1859452368] 'applied index is now lower than readState.Index' (duration: 278.503118ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:54.776633Z","caller":"traceutil/trace.go:171","msg":"trace[2135048427] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"556.116727ms","start":"2026-04-13T17:19:54.220502Z","end":"2026-04-13T17:19:54.776619Z","steps":["trace[2135048427] 'process raft request' (duration: 555.956352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:54.776713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:19:54.220494Z","time spent":"556.183759ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:19:54.776741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.34519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:19:54.776755Z","caller":"traceutil/trace.go:171","msg":"trace[1501865264] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"556.402075ms","start":"2026-04-13T17:19:54.220326Z","end":"2026-04-13T17:19:54.776728Z","steps":["trace[1501865264] 'process raft request' (duration: 357.50934ms)","trace[1501865264] 'compare' (duration: 198.236245ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:54.776814Z","caller":"traceutil/trace.go:171","msg":"trace[595978628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5366; }","duration":"431.425402ms","start":"2026-04-13T17:19:54.34537Z","end":"2026-04-13T17:19:54.776795Z","steps":["trace[595978628] 'agreement among raft nodes before linearized reading' (duration: 431.309539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:54.776856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:19:54.345317Z","time spent":"431.530496ms","remote":"127.0.0.1:51896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:19:54.776905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:19:54.220305Z","time spent":"556.520959ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:19:54.776995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.390156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-13T17:19:54.777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.706158ms","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-13T17:19:54.777013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.924568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T17:19:54.777069Z","caller":"traceutil/trace.go:171","msg":"trace[275538601] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5366; }","duration":"191.816882ms","start":"2026-04-13T17:19:54.585232Z","end":"2026-04-13T17:19:54.777049Z","steps":["trace[275538601] 'agreement among raft nodes before linearized reading' (duration: 191.569285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:54.777057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.332373ms","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-13T17:19:54.777108Z","caller":"traceutil/trace.go:171","msg":"trace[217443403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5366; }","duration":"181.054003ms","start":"2026-04-13T17:19:54.596031Z","end":"2026-04-13T17:19:54.777085Z","steps":["trace[217443403] 'agreement among raft nodes before linearized reading' (duration: 180.730452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:54.777096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.570638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T17:19:54.777134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.252176ms","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-13T17:19:54.777135Z","caller":"traceutil/trace.go:171","msg":"trace[959939517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5366; }","duration":"266.456968ms","start":"2026-04-13T17:19:54.510662Z","end":"2026-04-13T17:19:54.777119Z","steps":["trace[959939517] 'agreement among raft nodes before linearized reading' (duration: 266.22349ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:54.777159Z","caller":"traceutil/trace.go:171","msg":"trace[2026541372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5366; }","duration":"179.303058ms","start":"2026-04-13T17:19:54.597851Z","end":"2026-04-13T17:19:54.777154Z","steps":["trace[2026541372] 'agreement among raft nodes before linearized reading' (duration: 179.243856ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:54.777171Z","caller":"traceutil/trace.go:171","msg":"trace[1278972461] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:5366; }","duration":"277.682942ms","start":"2026-04-13T17:19:54.49947Z","end":"2026-04-13T17:19:54.777153Z","steps":["trace[1278972461] 'agreement among raft nodes before linearized reading' (duration: 277.549847ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:54.777058Z","caller":"traceutil/trace.go:171","msg":"trace[1052625712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5366; }","duration":"183.50537ms","start":"2026-04-13T17:19:54.593536Z","end":"2026-04-13T17:19:54.777041Z","steps":["trace[1052625712] 'agreement among raft nodes before linearized reading' (duration: 183.318754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:54.777515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.179804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0cf7f61a-2ff2-4d65-9a87-28086b4587da\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-04-13T17:19:54.77756Z","caller":"traceutil/trace.go:171","msg":"trace[1428678155] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0cf7f61a-2ff2-4d65-9a87-28086b4587da; range_end:; response_count:1; response_revision:5366; }","duration":"553.310098ms","start":"2026-04-13T17:19:54.224238Z","end":"2026-04-13T17:19:54.777548Z","steps":["trace[1428678155] 'agreement among raft nodes before linearized reading' (duration: 552.601036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:54.777636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:19:54.224226Z","time spent":"553.401381ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3976,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0cf7f61a-2ff2-4d65-9a87-28086b4587da\" "} {"level":"info","ts":"2026-04-13T17:20:00.795879Z","caller":"traceutil/trace.go:171","msg":"trace[714834493] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"244.885626ms","start":"2026-04-13T17:20:00.550966Z","end":"2026-04-13T17:20:00.795851Z","steps":["trace[714834493] 'process raft request' (duration: 243.082309ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:20:05.475623Z","caller":"traceutil/trace.go:171","msg":"trace[960984815] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"148.819746ms","start":"2026-04-13T17:20:05.326764Z","end":"2026-04-13T17:20:05.475584Z","steps":["trace[960984815] 'process raft request' (duration: 80.009944ms)","trace[960984815] 'compare' (duration: 68.519533ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:20:05.475656Z","caller":"traceutil/trace.go:171","msg":"trace[831684540] transaction","detail":"{read_only:false; response_revision:5522; number_of_response:1; }","duration":"148.481555ms","start":"2026-04-13T17:20:05.327141Z","end":"2026-04-13T17:20:05.475622Z","steps":["trace[831684540] 'process raft request' (duration: 148.342001ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:20:05.475706Z","caller":"traceutil/trace.go:171","msg":"trace[867831004] linearizableReadLoop","detail":"{readStateIndex:5729; appliedIndex:5725; }","duration":"127.41627ms","start":"2026-04-13T17:20:05.348263Z","end":"2026-04-13T17:20:05.47568Z","steps":["trace[867831004] 'read index received' (duration: 1.696663ms)","trace[867831004] 'applied index is now lower than readState.Index' (duration: 125.718477ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:20:05.475826Z","caller":"traceutil/trace.go:171","msg":"trace[1877509721] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"148.303769ms","start":"2026-04-13T17:20:05.32751Z","end":"2026-04-13T17:20:05.475813Z","steps":["trace[1877509721] 'process raft request' (duration: 148.039141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:20:05.475891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.622037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:20:05.475936Z","caller":"traceutil/trace.go:171","msg":"trace[55799371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5523; }","duration":"127.70597ms","start":"2026-04-13T17:20:05.348221Z","end":"2026-04-13T17:20:05.475927Z","steps":["trace[55799371] 'agreement among raft nodes before linearized reading' (duration: 127.625137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:20:05.475965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.076359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-13T17:20:05.47603Z","caller":"traceutil/trace.go:171","msg":"trace[1672726249] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5523; }","duration":"114.180433ms","start":"2026-04-13T17:20:05.361826Z","end":"2026-04-13T17:20:05.476006Z","steps":["trace[1672726249] 'agreement among raft nodes before linearized reading' (duration: 114.014608ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:20:32.608903Z","caller":"traceutil/trace.go:171","msg":"trace[1037035993] transaction","detail":"{read_only:false; response_revision:5789; number_of_response:1; }","duration":"168.013154ms","start":"2026-04-13T17:20:32.440862Z","end":"2026-04-13T17:20:32.608875Z","steps":["trace[1037035993] 'process raft request' (duration: 167.855549ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:20:51.722537Z","caller":"traceutil/trace.go:171","msg":"trace[2128751171] transaction","detail":"{read_only:false; response_revision:5930; number_of_response:1; }","duration":"122.276435ms","start":"2026-04-13T17:20:51.600231Z","end":"2026-04-13T17:20:51.722507Z","steps":["trace[2128751171] 'process raft request' (duration: 119.262759ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:21:14.98533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-04-13T17:21:15.061253Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"74.277493ms","hash":4064825152,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":33943552,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-13T17:21:15.061324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4064825152,"revision":2974,"compact-revision":-1} {"level":"info","ts":"2026-04-13T17:25:40.898315Z","caller":"traceutil/trace.go:171","msg":"trace[1056683684] linearizableReadLoop","detail":"{readStateIndex:8383; appliedIndex:8382; }","duration":"136.368888ms","start":"2026-04-13T17:25:40.761927Z","end":"2026-04-13T17:25:40.898296Z","steps":["trace[1056683684] 'read index received' (duration: 136.11178ms)","trace[1056683684] 'applied index is now lower than readState.Index' (duration: 256.218µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:40.898403Z","caller":"traceutil/trace.go:171","msg":"trace[1616463739] transaction","detail":"{read_only:false; response_revision:8095; number_of_response:1; }","duration":"198.902829ms","start":"2026-04-13T17:25:40.699467Z","end":"2026-04-13T17:25:40.89837Z","steps":["trace[1616463739] 'process raft request' (duration: 198.62391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:40.898525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.579735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-13T17:25:40.898525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.287628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T17:25:40.89856Z","caller":"traceutil/trace.go:171","msg":"trace[956150141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8095; }","duration":"136.668407ms","start":"2026-04-13T17:25:40.761885Z","end":"2026-04-13T17:25:40.898553Z","steps":["trace[956150141] 'agreement among raft nodes before linearized reading' (duration: 136.520753ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:40.898571Z","caller":"traceutil/trace.go:171","msg":"trace[1463938052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8095; }","duration":"111.389991ms","start":"2026-04-13T17:25:40.787172Z","end":"2026-04-13T17:25:40.898562Z","steps":["trace[1463938052] 'agreement among raft nodes before linearized reading' (duration: 111.270287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:40.898721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.187121ms","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-13T17:25:40.898805Z","caller":"traceutil/trace.go:171","msg":"trace[1040460772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8095; }","duration":"102.313125ms","start":"2026-04-13T17:25:40.796473Z","end":"2026-04-13T17:25:40.898786Z","steps":["trace[1040460772] 'agreement among raft nodes before linearized reading' (duration: 102.083037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:42.585127Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139747488558862554,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:25:42.95336Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.035146213s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T17:25:42.953933Z","caller":"traceutil/trace.go:171","msg":"trace[686318622] transaction","detail":"{read_only:false; response_revision:8101; number_of_response:1; }","duration":"1.035848716s","start":"2026-04-13T17:25:41.918057Z","end":"2026-04-13T17:25:42.953906Z","steps":["trace[686318622] 'process raft request' (duration: 1.03566733s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:42.954116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:41.918031Z","time spent":"1.03599194s","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:25:42.955126Z","caller":"traceutil/trace.go:171","msg":"trace[933795453] linearizableReadLoop","detail":"{readStateIndex:8391; appliedIndex:8388; }","duration":"871.00941ms","start":"2026-04-13T17:25:42.084094Z","end":"2026-04-13T17:25:42.955103Z","steps":["trace[933795453] 'read index received' (duration: 869.583776ms)","trace[933795453] 'applied index is now lower than readState.Index' (duration: 1.424354ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:42.955173Z","caller":"traceutil/trace.go:171","msg":"trace[1171515966] transaction","detail":"{read_only:false; response_revision:8103; number_of_response:1; }","duration":"732.041581ms","start":"2026-04-13T17:25:42.223103Z","end":"2026-04-13T17:25:42.955145Z","steps":["trace[1171515966] 'process raft request' (duration: 731.955418ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:42.955179Z","caller":"traceutil/trace.go:171","msg":"trace[2019763518] transaction","detail":"{read_only:false; response_revision:8102; number_of_response:1; }","duration":"752.30084ms","start":"2026-04-13T17:25:42.202835Z","end":"2026-04-13T17:25:42.955136Z","steps":["trace[2019763518] 'process raft request' (duration: 752.087873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:42.955274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.169175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:42.955313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:42.223078Z","time spent":"732.144814ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:25:42.955424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:42.202798Z","time spent":"752.483025ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:25:42.955321Z","caller":"traceutil/trace.go:171","msg":"trace[543040090] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8103; }","duration":"871.266699ms","start":"2026-04-13T17:25:42.084044Z","end":"2026-04-13T17:25:42.95531Z","steps":["trace[543040090] 'agreement among raft nodes before linearized reading' (duration: 871.177106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:42.955421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.054465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:42.95549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:42.084023Z","time spent":"871.454624ms","remote":"127.0.0.1:52336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-04-13T17:25:42.955518Z","caller":"traceutil/trace.go:171","msg":"trace[949341181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8103; }","duration":"609.200899ms","start":"2026-04-13T17:25:42.346303Z","end":"2026-04-13T17:25:42.955504Z","steps":["trace[949341181] 'agreement among raft nodes before linearized reading' (duration: 609.046374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:42.955555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:42.346278Z","time spent":"609.267801ms","remote":"127.0.0.1:51896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T17:25:55.93878Z","caller":"traceutil/trace.go:171","msg":"trace[1030129772] linearizableReadLoop","detail":"{readStateIndex:8464; appliedIndex:8463; }","duration":"290.21542ms","start":"2026-04-13T17:25:55.648534Z","end":"2026-04-13T17:25:55.938749Z","steps":["trace[1030129772] 'read index received' (duration: 289.941301ms)","trace[1030129772] 'applied index is now lower than readState.Index' (duration: 272.609µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:55.938899Z","caller":"traceutil/trace.go:171","msg":"trace[250808432] transaction","detail":"{read_only:false; response_revision:8172; number_of_response:1; }","duration":"483.271995ms","start":"2026-04-13T17:25:55.455583Z","end":"2026-04-13T17:25:55.938855Z","steps":["trace[250808432] 'process raft request' (duration: 482.927234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.448427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.939064Z","caller":"traceutil/trace.go:171","msg":"trace[339685627] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8172; }","duration":"290.55856ms","start":"2026-04-13T17:25:55.648493Z","end":"2026-04-13T17:25:55.939052Z","steps":["trace[339685627] 'agreement among raft nodes before linearized reading' (duration: 290.422596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:55.45555Z","time spent":"483.457772ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:25:55.939196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.319778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.921119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.939314Z","caller":"traceutil/trace.go:171","msg":"trace[970071051] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8172; }","duration":"136.476472ms","start":"2026-04-13T17:25:55.802799Z","end":"2026-04-13T17:25:55.939276Z","steps":["trace[970071051] 'agreement among raft nodes before linearized reading' (duration: 136.245435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.210183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.401665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.493263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.64199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.939404Z","caller":"traceutil/trace.go:171","msg":"trace[1380212794] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8172; }","duration":"251.608552ms","start":"2026-04-13T17:25:55.687784Z","end":"2026-04-13T17:25:55.939393Z","steps":["trace[1380212794] 'agreement among raft nodes before linearized reading' (duration: 251.354394ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.939417Z","caller":"traceutil/trace.go:171","msg":"trace[1316075676] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8172; }","duration":"169.554055ms","start":"2026-04-13T17:25:55.769853Z","end":"2026-04-13T17:25:55.939408Z","steps":["trace[1316075676] 'agreement among raft nodes before linearized reading' (duration: 169.483123ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.939419Z","caller":"traceutil/trace.go:171","msg":"trace[299053006] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8172; }","duration":"207.286975ms","start":"2026-04-13T17:25:55.732107Z","end":"2026-04-13T17:25:55.939394Z","steps":["trace[299053006] 'agreement among raft nodes before linearized reading' (duration: 207.226953ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.939445Z","caller":"traceutil/trace.go:171","msg":"trace[1962392720] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8172; }","duration":"170.734533ms","start":"2026-04-13T17:25:55.768691Z","end":"2026-04-13T17:25:55.939425Z","steps":["trace[1962392720] 'agreement among raft nodes before linearized reading' (duration: 170.62656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.59062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.029503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.460154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.537496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-13T17:25:55.939683Z","caller":"traceutil/trace.go:171","msg":"trace[54973078] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8172; }","duration":"222.614528ms","start":"2026-04-13T17:25:55.717063Z","end":"2026-04-13T17:25:55.939677Z","steps":["trace[54973078] 'agreement among raft nodes before linearized reading' (duration: 222.487034ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.93968Z","caller":"traceutil/trace.go:171","msg":"trace[1081281150] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8172; }","duration":"170.710681ms","start":"2026-04-13T17:25:55.768951Z","end":"2026-04-13T17:25:55.939661Z","steps":["trace[1081281150] 'agreement among raft nodes before linearized reading' (duration: 170.448583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.241769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.134758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.86464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.939764Z","caller":"traceutil/trace.go:171","msg":"trace[1617189835] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8172; }","duration":"270.330032ms","start":"2026-04-13T17:25:55.669425Z","end":"2026-04-13T17:25:55.939755Z","steps":["trace[1617189835] 'agreement among raft nodes before linearized reading' (duration: 270.273021ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.939781Z","caller":"traceutil/trace.go:171","msg":"trace[1911091242] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8172; }","duration":"251.182089ms","start":"2026-04-13T17:25:55.688587Z","end":"2026-04-13T17:25:55.939769Z","steps":["trace[1911091242] 'agreement among raft nodes before linearized reading' (duration: 251.130547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.42105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.93967Z","caller":"traceutil/trace.go:171","msg":"trace[2091370562] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8172; }","duration":"270.275711ms","start":"2026-04-13T17:25:55.669372Z","end":"2026-04-13T17:25:55.939648Z","steps":["trace[2091370562] 'agreement among raft nodes before linearized reading' (duration: 270.005022ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.939783Z","caller":"traceutil/trace.go:171","msg":"trace[1064656164] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8172; }","duration":"251.998735ms","start":"2026-04-13T17:25:55.687775Z","end":"2026-04-13T17:25:55.939773Z","steps":["trace[1064656164] 'agreement among raft nodes before linearized reading' (duration: 251.882541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.503383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.432626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.248731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.111922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.93993Z","caller":"traceutil/trace.go:171","msg":"trace[1616239238] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8172; }","duration":"291.329744ms","start":"2026-04-13T17:25:55.648588Z","end":"2026-04-13T17:25:55.939918Z","steps":["trace[1616239238] 'agreement among raft nodes before linearized reading' (duration: 290.413096ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.939941Z","caller":"traceutil/trace.go:171","msg":"trace[1263930023] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8172; }","duration":"103.597966ms","start":"2026-04-13T17:25:55.836308Z","end":"2026-04-13T17:25:55.939906Z","steps":["trace[1263930023] 'agreement among raft nodes before linearized reading' (duration: 103.464082ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.939956Z","caller":"traceutil/trace.go:171","msg":"trace[48764659] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8172; }","duration":"104.137883ms","start":"2026-04-13T17:25:55.835813Z","end":"2026-04-13T17:25:55.939951Z","steps":["trace[48764659] 'agreement among raft nodes before linearized reading' (duration: 104.100951ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.939958Z","caller":"traceutil/trace.go:171","msg":"trace[1655051635] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8172; }","duration":"153.360335ms","start":"2026-04-13T17:25:55.786575Z","end":"2026-04-13T17:25:55.939935Z","steps":["trace[1655051635] 'agreement among raft nodes before linearized reading' (duration: 153.246321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.768596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.940029Z","caller":"traceutil/trace.go:171","msg":"trace[953944189] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8172; }","duration":"119.815158ms","start":"2026-04-13T17:25:55.820203Z","end":"2026-04-13T17:25:55.940019Z","steps":["trace[953944189] 'agreement among raft nodes before linearized reading' (duration: 119.771866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.168944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.940101Z","caller":"traceutil/trace.go:171","msg":"trace[1460227986] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8172; }","duration":"104.413742ms","start":"2026-04-13T17:25:55.835671Z","end":"2026-04-13T17:25:55.940085Z","steps":["trace[1460227986] 'agreement among raft nodes before linearized reading' (duration: 104.210105ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.939587Z","caller":"traceutil/trace.go:171","msg":"trace[985954170] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8172; }","duration":"270.698593ms","start":"2026-04-13T17:25:55.66887Z","end":"2026-04-13T17:25:55.939568Z","steps":["trace[985954170] 'agreement among raft nodes before linearized reading' (duration: 270.572669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.89499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.940165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.329385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.940172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.700295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.724239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.940213Z","caller":"traceutil/trace.go:171","msg":"trace[1913405093] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8172; }","duration":"291.415687ms","start":"2026-04-13T17:25:55.648779Z","end":"2026-04-13T17:25:55.940195Z","steps":["trace[1913405093] 'agreement among raft nodes before linearized reading' (duration: 291.304764ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.94021Z","caller":"traceutil/trace.go:171","msg":"trace[405105962] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8172; }","duration":"153.649544ms","start":"2026-04-13T17:25:55.786542Z","end":"2026-04-13T17:25:55.940192Z","steps":["trace[405105962] 'agreement among raft nodes before linearized reading' (duration: 152.787337ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.940222Z","caller":"traceutil/trace.go:171","msg":"trace[1697186614] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8172; }","duration":"120.767147ms","start":"2026-04-13T17:25:55.819436Z","end":"2026-04-13T17:25:55.940203Z","steps":["trace[1697186614] 'agreement among raft nodes before linearized reading' (duration: 120.704126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.940068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.450297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.939836Z","caller":"traceutil/trace.go:171","msg":"trace[1324746085] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8172; }","duration":"188.506922ms","start":"2026-04-13T17:25:55.751317Z","end":"2026-04-13T17:25:55.939824Z","steps":["trace[1324746085] 'agreement among raft nodes before linearized reading' (duration: 188.452411ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.940261Z","caller":"traceutil/trace.go:171","msg":"trace[991655049] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8172; }","duration":"120.84073ms","start":"2026-04-13T17:25:55.81941Z","end":"2026-04-13T17:25:55.94025Z","steps":["trace[991655049] 'agreement among raft nodes before linearized reading' (duration: 120.418457ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.940224Z","caller":"traceutil/trace.go:171","msg":"trace[992619378] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8172; }","duration":"208.170173ms","start":"2026-04-13T17:25:55.732042Z","end":"2026-04-13T17:25:55.940212Z","steps":["trace[992619378] 'agreement among raft nodes before linearized reading' (duration: 207.746849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.637601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rgdx4\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-13T17:25:55.9399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.200606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:55.939191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.024477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.940372Z","caller":"traceutil/trace.go:171","msg":"trace[96015701] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8172; }","duration":"190.732293ms","start":"2026-04-13T17:25:55.749633Z","end":"2026-04-13T17:25:55.940366Z","steps":["trace[96015701] 'agreement among raft nodes before linearized reading' (duration: 190.223627ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.940374Z","caller":"traceutil/trace.go:171","msg":"trace[893452133] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rgdx4; range_end:; response_count:1; response_revision:8172; }","duration":"172.25581ms","start":"2026-04-13T17:25:55.76807Z","end":"2026-04-13T17:25:55.940326Z","steps":["trace[893452133] 'agreement among raft nodes before linearized reading' (duration: 171.548558ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.940416Z","caller":"traceutil/trace.go:171","msg":"trace[1882918064] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8172; }","duration":"208.285407ms","start":"2026-04-13T17:25:55.732112Z","end":"2026-04-13T17:25:55.940397Z","steps":["trace[1882918064] 'agreement among raft nodes before linearized reading' (duration: 207.014566ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:55.939363Z","caller":"traceutil/trace.go:171","msg":"trace[610249124] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8172; }","duration":"152.002102ms","start":"2026-04-13T17:25:55.78734Z","end":"2026-04-13T17:25:55.939342Z","steps":["trace[610249124] 'agreement among raft nodes before linearized reading' (duration: 151.92175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.939943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.569978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.940743Z","caller":"traceutil/trace.go:171","msg":"trace[1181499053] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8172; }","duration":"191.316332ms","start":"2026-04-13T17:25:55.749339Z","end":"2026-04-13T17:25:55.940656Z","steps":["trace[1181499053] 'agreement among raft nodes before linearized reading' (duration: 190.581209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.940137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.501043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.941089Z","caller":"traceutil/trace.go:171","msg":"trace[1858266697] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8172; }","duration":"137.467004ms","start":"2026-04-13T17:25:55.80361Z","end":"2026-04-13T17:25:55.941077Z","steps":["trace[1858266697] 'agreement among raft nodes before linearized reading' (duration: 136.492303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:55.940591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.496334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:55.941322Z","caller":"traceutil/trace.go:171","msg":"trace[1432086091] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8172; }","duration":"138.242448ms","start":"2026-04-13T17:25:55.803064Z","end":"2026-04-13T17:25:55.941307Z","steps":["trace[1432086091] 'agreement among raft nodes before linearized reading' (duration: 137.500065ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:56.67729Z","caller":"traceutil/trace.go:171","msg":"trace[1165643864] transaction","detail":"{read_only:false; response_revision:8173; number_of_response:1; }","duration":"122.292095ms","start":"2026-04-13T17:25:56.554964Z","end":"2026-04-13T17:25:56.677256Z","steps":["trace[1165643864] 'process raft request' (duration: 122.040927ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:57.208419Z","caller":"traceutil/trace.go:171","msg":"trace[481340616] transaction","detail":"{read_only:false; response_revision:8174; number_of_response:1; }","duration":"188.663848ms","start":"2026-04-13T17:25:57.019729Z","end":"2026-04-13T17:25:57.208393Z","steps":["trace[481340616] 'process raft request' (duration: 188.475002ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:57.319767Z","caller":"traceutil/trace.go:171","msg":"trace[1195185135] linearizableReadLoop","detail":"{readStateIndex:8468; appliedIndex:8465; }","duration":"261.196874ms","start":"2026-04-13T17:25:57.058545Z","end":"2026-04-13T17:25:57.319742Z","steps":["trace[1195185135] 'read index received' (duration: 149.680648ms)","trace[1195185135] 'applied index is now lower than readState.Index' (duration: 111.515166ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:57.319796Z","caller":"traceutil/trace.go:171","msg":"trace[1280587156] transaction","detail":"{read_only:false; response_revision:8175; number_of_response:1; }","duration":"289.308901ms","start":"2026-04-13T17:25:57.030456Z","end":"2026-04-13T17:25:57.319765Z","steps":["trace[1280587156] 'process raft request' (duration: 278.583593ms)","trace[1280587156] 'compare' (duration: 10.49234ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:57.319838Z","caller":"traceutil/trace.go:171","msg":"trace[1139405571] transaction","detail":"{read_only:false; response_revision:8176; number_of_response:1; }","duration":"288.576278ms","start":"2026-04-13T17:25:57.031226Z","end":"2026-04-13T17:25:57.319802Z","steps":["trace[1139405571] 'process raft request' (duration: 288.460484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:57.319966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.393741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:57.320033Z","caller":"traceutil/trace.go:171","msg":"trace[1543796304] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:8176; }","duration":"261.528345ms","start":"2026-04-13T17:25:57.058489Z","end":"2026-04-13T17:25:57.320017Z","steps":["trace[1543796304] 'agreement among raft nodes before linearized reading' (duration: 261.341199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:57.320084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.691869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-13T17:25:57.320116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.887478ms","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-13T17:25:57.320127Z","caller":"traceutil/trace.go:171","msg":"trace[342927667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8176; }","duration":"175.781912ms","start":"2026-04-13T17:25:57.144335Z","end":"2026-04-13T17:25:57.320117Z","steps":["trace[342927667] 'agreement among raft nodes before linearized reading' (duration: 175.677659ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:57.320151Z","caller":"traceutil/trace.go:171","msg":"trace[627415629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8176; }","duration":"190.96445ms","start":"2026-04-13T17:25:57.12918Z","end":"2026-04-13T17:25:57.320144Z","steps":["trace[627415629] 'agreement among raft nodes before linearized reading' (duration: 190.846176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:57.320242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.405133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T17:25:57.320273Z","caller":"traceutil/trace.go:171","msg":"trace[1567901334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8176; }","duration":"190.474294ms","start":"2026-04-13T17:25:57.129791Z","end":"2026-04-13T17:25:57.320265Z","steps":["trace[1567901334] 'agreement among raft nodes before linearized reading' (duration: 190.363951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:57.320315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.47582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-13T17:25:57.320282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.592037ms","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-13T17:25:57.320343Z","caller":"traceutil/trace.go:171","msg":"trace[2143727019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8176; }","duration":"200.523762ms","start":"2026-04-13T17:25:57.119811Z","end":"2026-04-13T17:25:57.320335Z","steps":["trace[2143727019] 'agreement among raft nodes before linearized reading' (duration: 200.435039ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:57.320396Z","caller":"traceutil/trace.go:171","msg":"trace[776981662] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8176; }","duration":"189.757442ms","start":"2026-04-13T17:25:57.130614Z","end":"2026-04-13T17:25:57.320371Z","steps":["trace[776981662] 'agreement among raft nodes before linearized reading' (duration: 189.38689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:57.320787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.140117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T17:25:57.320833Z","caller":"traceutil/trace.go:171","msg":"trace[1337938312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8176; }","duration":"211.218509ms","start":"2026-04-13T17:25:57.109604Z","end":"2026-04-13T17:25:57.320822Z","steps":["trace[1337938312] 'agreement among raft nodes before linearized reading' (duration: 210.28932ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:57.593909Z","caller":"traceutil/trace.go:171","msg":"trace[1662187860] transaction","detail":"{read_only:false; response_revision:8177; number_of_response:1; }","duration":"266.805532ms","start":"2026-04-13T17:25:57.327077Z","end":"2026-04-13T17:25:57.593882Z","steps":["trace[1662187860] 'process raft request' (duration: 266.616446ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:57.769124Z","caller":"traceutil/trace.go:171","msg":"trace[1602469678] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"438.942068ms","start":"2026-04-13T17:25:57.330151Z","end":"2026-04-13T17:25:57.769093Z","steps":["trace[1602469678] 'process raft request' (duration: 373.177275ms)","trace[1602469678] 'compare' (duration: 65.473204ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:25:57.769298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:57.330137Z","time spent":"439.073452ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:25:57.769417Z","caller":"traceutil/trace.go:171","msg":"trace[1712434222] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"439.119383ms","start":"2026-04-13T17:25:57.330267Z","end":"2026-04-13T17:25:57.769386Z","steps":["trace[1712434222] 'process raft request' (duration: 438.755022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:57.769596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:57.330261Z","time spent":"439.243257ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:25:57.769718Z","caller":"traceutil/trace.go:171","msg":"trace[1992573895] linearizableReadLoop","detail":"{readStateIndex:8472; appliedIndex:8468; }","duration":"438.864665ms","start":"2026-04-13T17:25:57.33084Z","end":"2026-04-13T17:25:57.769705Z","steps":["trace[1992573895] 'read index received' (duration: 262.908048ms)","trace[1992573895] 'applied index is now lower than readState.Index' (duration: 175.955247ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:57.769724Z","caller":"traceutil/trace.go:171","msg":"trace[819255875] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"439.243268ms","start":"2026-04-13T17:25:57.330446Z","end":"2026-04-13T17:25:57.76969Z","steps":["trace[819255875] 'process raft request' (duration: 438.860406ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:57.769638Z","caller":"traceutil/trace.go:171","msg":"trace[1028798151] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"438.767102ms","start":"2026-04-13T17:25:57.330835Z","end":"2026-04-13T17:25:57.769603Z","steps":["trace[1028798151] 'process raft request' (duration: 438.662889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:57.769799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.950458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:25:57.769761Z","caller":"traceutil/trace.go:171","msg":"trace[1554236224] transaction","detail":"{read_only:false; response_revision:8182; number_of_response:1; }","duration":"436.586623ms","start":"2026-04-13T17:25:57.33315Z","end":"2026-04-13T17:25:57.769737Z","steps":["trace[1554236224] 'process raft request' (duration: 436.396067ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:57.76983Z","caller":"traceutil/trace.go:171","msg":"trace[2103280968] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8182; }","duration":"439.01326ms","start":"2026-04-13T17:25:57.330809Z","end":"2026-04-13T17:25:57.769822Z","steps":["trace[2103280968] 'agreement among raft nodes before linearized reading' (duration: 438.942128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:57.769855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:57.3308Z","time spent":"439.048491ms","remote":"127.0.0.1:38738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:25:57.769846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:57.330808Z","time spent":"438.996089ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:25:57.769872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:57.33042Z","time spent":"439.373481ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:25:57.769978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:57.333121Z","time spent":"436.730918ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:25:57.813142Z","caller":"traceutil/trace.go:171","msg":"trace[1191317585] transaction","detail":"{read_only:false; response_revision:8183; number_of_response:1; }","duration":"131.149435ms","start":"2026-04-13T17:25:57.681965Z","end":"2026-04-13T17:25:57.813114Z","steps":["trace[1191317585] 'process raft request' (duration: 130.969299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:58.454997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139747488558862786,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T17:25:58.51503Z","caller":"traceutil/trace.go:171","msg":"trace[1936994369] linearizableReadLoop","detail":"{readStateIndex:8477; appliedIndex:8476; }","duration":"560.930393ms","start":"2026-04-13T17:25:57.954065Z","end":"2026-04-13T17:25:58.514996Z","steps":["trace[1936994369] 'read index received' (duration: 560.657274ms)","trace[1936994369] 'applied index is now lower than readState.Index' (duration: 271.279µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:25:58.515059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:57.949369Z","time spent":"565.679773ms","remote":"127.0.0.1:51914","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-13T17:25:58.515299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.014469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:58.51536Z","caller":"traceutil/trace.go:171","msg":"trace[1054485536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8184; }","duration":"170.131872ms","start":"2026-04-13T17:25:58.345215Z","end":"2026-04-13T17:25:58.515347Z","steps":["trace[1054485536] 'agreement among raft nodes before linearized reading' (duration: 170.033199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:58.515287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.191281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rgdx4\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-13T17:25:58.515492Z","caller":"traceutil/trace.go:171","msg":"trace[1155070080] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rgdx4; range_end:; response_count:1; response_revision:8184; }","duration":"561.430749ms","start":"2026-04-13T17:25:57.954028Z","end":"2026-04-13T17:25:58.515459Z","steps":["trace[1155070080] 'agreement among raft nodes before linearized reading' (duration: 561.066947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:58.515296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.975799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:25:58.515641Z","caller":"traceutil/trace.go:171","msg":"trace[1769122030] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8184; }","duration":"394.408203ms","start":"2026-04-13T17:25:58.121214Z","end":"2026-04-13T17:25:58.515622Z","steps":["trace[1769122030] 'agreement among raft nodes before linearized reading' (duration: 393.990239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:58.515692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:58.121166Z","time spent":"394.512026ms","remote":"127.0.0.1:52196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:25:58.515589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:57.954011Z","time spent":"561.544062ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-rgdx4\" "} {"level":"info","ts":"2026-04-13T17:25:58.730939Z","caller":"traceutil/trace.go:171","msg":"trace[721441477] linearizableReadLoop","detail":"{readStateIndex:8478; appliedIndex:8477; }","duration":"211.106735ms","start":"2026-04-13T17:25:58.519801Z","end":"2026-04-13T17:25:58.730908Z","steps":["trace[721441477] 'read index received' (duration: 203.903768ms)","trace[721441477] 'applied index is now lower than readState.Index' (duration: 7.201497ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:58.730966Z","caller":"traceutil/trace.go:171","msg":"trace[1870043519] transaction","detail":"{read_only:false; response_revision:8185; number_of_response:1; }","duration":"214.519253ms","start":"2026-04-13T17:25:58.516407Z","end":"2026-04-13T17:25:58.730926Z","steps":["trace[1870043519] 'process raft request' (duration: 207.366648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:58.731343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.465596ms","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-13T17:25:58.731404Z","caller":"traceutil/trace.go:171","msg":"trace[943869302] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8185; }","duration":"145.555889ms","start":"2026-04-13T17:25:58.585835Z","end":"2026-04-13T17:25:58.731391Z","steps":["trace[943869302] 'agreement among raft nodes before linearized reading' (duration: 145.26563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:58.731664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.852269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223824"} {"level":"info","ts":"2026-04-13T17:25:58.731752Z","caller":"traceutil/trace.go:171","msg":"trace[2044072161] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8185; }","duration":"211.984113ms","start":"2026-04-13T17:25:58.519747Z","end":"2026-04-13T17:25:58.731731Z","steps":["trace[2044072161] 'agreement among raft nodes before linearized reading' (duration: 211.26851ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:15.00384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6107} {"level":"info","ts":"2026-04-13T17:26:15.298762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6107,"took":"291.52942ms","hash":2642049600,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":23048192,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-13T17:26:15.298827Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2642049600,"revision":6107,"compact-revision":2974} {"level":"info","ts":"2026-04-13T17:26:23.858794Z","caller":"traceutil/trace.go:171","msg":"trace[1369028155] transaction","detail":"{read_only:false; response_revision:8371; number_of_response:1; }","duration":"134.793419ms","start":"2026-04-13T17:26:23.723987Z","end":"2026-04-13T17:26:23.858781Z","steps":["trace[1369028155] 'process raft request' (duration: 134.558422ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:23.858775Z","caller":"traceutil/trace.go:171","msg":"trace[1804216412] linearizableReadLoop","detail":"{readStateIndex:8672; appliedIndex:8671; }","duration":"124.773924ms","start":"2026-04-13T17:26:23.733972Z","end":"2026-04-13T17:26:23.858746Z","steps":["trace[1804216412] 'read index received' (duration: 124.559177ms)","trace[1804216412] 'applied index is now lower than readState.Index' (duration: 213.627µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:26:23.858917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.97319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:26:23.858975Z","caller":"traceutil/trace.go:171","msg":"trace[45609042] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:8371; }","duration":"125.129535ms","start":"2026-04-13T17:26:23.733833Z","end":"2026-04-13T17:26:23.858962Z","steps":["trace[45609042] 'agreement among raft nodes before linearized reading' (duration: 124.97251ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:25.461548Z","caller":"traceutil/trace.go:171","msg":"trace[471457809] linearizableReadLoop","detail":"{readStateIndex:8680; appliedIndex:8679; }","duration":"114.932433ms","start":"2026-04-13T17:26:25.346599Z","end":"2026-04-13T17:26:25.461532Z","steps":["trace[471457809] 'read index received' (duration: 114.849051ms)","trace[471457809] 'applied index is now lower than readState.Index' (duration: 82.642µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:26:25.461645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.025356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:26:25.461669Z","caller":"traceutil/trace.go:171","msg":"trace[306761927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8379; }","duration":"115.087838ms","start":"2026-04-13T17:26:25.346572Z","end":"2026-04-13T17:26:25.46166Z","steps":["trace[306761927] 'agreement among raft nodes before linearized reading' (duration: 115.028927ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:25.461666Z","caller":"traceutil/trace.go:171","msg":"trace[1165816631] transaction","detail":"{read_only:false; response_revision:8379; number_of_response:1; }","duration":"129.155641ms","start":"2026-04-13T17:26:25.332437Z","end":"2026-04-13T17:26:25.461593Z","steps":["trace[1165816631] 'process raft request' (duration: 128.981146ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:27.969307Z","caller":"traceutil/trace.go:171","msg":"trace[1728057844] transaction","detail":"{read_only:false; response_revision:8390; number_of_response:1; }","duration":"500.424987ms","start":"2026-04-13T17:26:27.468852Z","end":"2026-04-13T17:26:27.969277Z","steps":["trace[1728057844] 'process raft request' (duration: 500.184949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:27.969474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:27.468834Z","time spent":"500.555ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:27.970043Z","caller":"traceutil/trace.go:171","msg":"trace[17176387] transaction","detail":"{read_only:false; response_revision:8391; number_of_response:1; }","duration":"500.5409ms","start":"2026-04-13T17:26:27.469481Z","end":"2026-04-13T17:26:27.970022Z","steps":["trace[17176387] 'process raft request' (duration: 500.369255ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:27.970772Z","caller":"traceutil/trace.go:171","msg":"trace[1642946945] transaction","detail":"{read_only:false; response_revision:8392; number_of_response:1; }","duration":"500.957853ms","start":"2026-04-13T17:26:27.469781Z","end":"2026-04-13T17:26:27.970739Z","steps":["trace[1642946945] 'process raft request' (duration: 500.176758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:27.970939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:27.469756Z","time spent":"501.109728ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:26:27.971631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:27.469465Z","time spent":"500.636013ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:30.205368Z","caller":"traceutil/trace.go:171","msg":"trace[1074392929] transaction","detail":"{read_only:false; response_revision:8403; number_of_response:1; }","duration":"227.898325ms","start":"2026-04-13T17:26:29.97743Z","end":"2026-04-13T17:26:30.205329Z","steps":["trace[1074392929] 'process raft request' (duration: 227.635177ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:30.409662Z","caller":"traceutil/trace.go:171","msg":"trace[15415510] linearizableReadLoop","detail":"{readStateIndex:8707; appliedIndex:8704; }","duration":"427.177198ms","start":"2026-04-13T17:26:29.982451Z","end":"2026-04-13T17:26:30.409628Z","steps":["trace[15415510] 'read index received' (duration: 222.551137ms)","trace[15415510] 'applied index is now lower than readState.Index' (duration: 204.624381ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:26:30.409733Z","caller":"traceutil/trace.go:171","msg":"trace[961579498] transaction","detail":"{read_only:false; response_revision:8404; number_of_response:1; }","duration":"429.533902ms","start":"2026-04-13T17:26:29.980159Z","end":"2026-04-13T17:26:30.409693Z","steps":["trace[961579498] 'process raft request' (duration: 428.966104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.409923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:29.98014Z","time spent":"429.677546ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:26:30.409973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.430496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-13T17:26:30.409985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.614334ms","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":"warn","ts":"2026-04-13T17:26:30.410026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.49976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-13T17:26:30.410041Z","caller":"traceutil/trace.go:171","msg":"trace[908373194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8405; }","duration":"427.522638ms","start":"2026-04-13T17:26:29.982504Z","end":"2026-04-13T17:26:30.410027Z","steps":["trace[908373194] 'agreement among raft nodes before linearized reading' (duration: 427.335592ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:30.410052Z","caller":"traceutil/trace.go:171","msg":"trace[1902551726] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8405; }","duration":"409.570212ms","start":"2026-04-13T17:26:30.000474Z","end":"2026-04-13T17:26:30.410044Z","steps":["trace[1902551726] 'agreement among raft nodes before linearized reading' (duration: 409.433977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.410024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.216987ms","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-13T17:26:30.410076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:29.982498Z","time spent":"427.57177ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T17:26:30.41005Z","caller":"traceutil/trace.go:171","msg":"trace[1042195622] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8405; }","duration":"167.727958ms","start":"2026-04-13T17:26:30.242308Z","end":"2026-04-13T17:26:30.410036Z","steps":["trace[1042195622] 'agreement among raft nodes before linearized reading' (duration: 167.589883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.410088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:30.000457Z","time spent":"409.624764ms","remote":"127.0.0.1:52202","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":"warn","ts":"2026-04-13T17:26:30.410099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.62841ms","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-13T17:26:30.410104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.639882ms","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-13T17:26:30.410136Z","caller":"traceutil/trace.go:171","msg":"trace[1384837141] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8405; }","duration":"426.344251ms","start":"2026-04-13T17:26:29.98377Z","end":"2026-04-13T17:26:30.410114Z","steps":["trace[1384837141] 'agreement among raft nodes before linearized reading' (duration: 426.115714ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:30.409811Z","caller":"traceutil/trace.go:171","msg":"trace[1617608552] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"428.038264ms","start":"2026-04-13T17:26:29.981759Z","end":"2026-04-13T17:26:30.409798Z","steps":["trace[1617608552] 'process raft request' (duration: 427.765005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.41018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:29.983739Z","time spent":"426.430934ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-13T17:26:30.410173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.741963ms","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-13T17:26:30.410228Z","caller":"traceutil/trace.go:171","msg":"trace[1201777091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8405; }","duration":"426.809475ms","start":"2026-04-13T17:26:29.983404Z","end":"2026-04-13T17:26:30.410213Z","steps":["trace[1201777091] 'agreement among raft nodes before linearized reading' (duration: 426.63633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.410246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:29.981739Z","time spent":"428.440077ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:26:30.410256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:29.983397Z","time spent":"426.851207ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T17:26:30.410179Z","caller":"traceutil/trace.go:171","msg":"trace[1029641275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8405; }","duration":"427.740775ms","start":"2026-04-13T17:26:29.982421Z","end":"2026-04-13T17:26:30.410161Z","steps":["trace[1029641275] 'agreement among raft nodes before linearized reading' (duration: 427.536549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.410257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.473965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T17:26:30.410337Z","caller":"traceutil/trace.go:171","msg":"trace[959891000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8405; }","duration":"426.615159ms","start":"2026-04-13T17:26:29.983706Z","end":"2026-04-13T17:26:30.410322Z","steps":["trace[959891000] 'agreement among raft nodes before linearized reading' (duration: 426.365742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.410371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:29.983678Z","time spent":"426.686372ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T17:26:30.41013Z","caller":"traceutil/trace.go:171","msg":"trace[1000280095] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8405; }","duration":"426.685461ms","start":"2026-04-13T17:26:29.983437Z","end":"2026-04-13T17:26:30.410122Z","steps":["trace[1000280095] 'agreement among raft nodes before linearized reading' (duration: 426.549127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.410353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:29.982408Z","time spent":"427.90725ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:26:30.410421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:29.983434Z","time spent":"426.966041ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T17:26:30.719666Z","caller":"traceutil/trace.go:171","msg":"trace[1607967090] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"306.190622ms","start":"2026-04-13T17:26:30.413433Z","end":"2026-04-13T17:26:30.719624Z","steps":["trace[1607967090] 'process raft request' (duration: 273.77442ms)","trace[1607967090] 'compare' (duration: 32.214686ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:26:30.720099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:30.413408Z","time spent":"306.346367ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:30.734033Z","caller":"traceutil/trace.go:171","msg":"trace[1277619831] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"319.866023ms","start":"2026-04-13T17:26:30.414143Z","end":"2026-04-13T17:26:30.734009Z","steps":["trace[1277619831] 'process raft request' (duration: 319.677367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.73419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:30.414121Z","time spent":"319.985977ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:30.734373Z","caller":"traceutil/trace.go:171","msg":"trace[478889415] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"320.090011ms","start":"2026-04-13T17:26:30.414251Z","end":"2026-04-13T17:26:30.734341Z","steps":["trace[478889415] 'process raft request' (duration: 319.712489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.734523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:30.414229Z","time spent":"320.217045ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:30.734541Z","caller":"traceutil/trace.go:171","msg":"trace[313140021] linearizableReadLoop","detail":"{readStateIndex:8714; appliedIndex:8707; }","duration":"194.013696ms","start":"2026-04-13T17:26:30.5405Z","end":"2026-04-13T17:26:30.734513Z","steps":["trace[313140021] 'read index received' (duration: 146.694944ms)","trace[313140021] 'applied index is now lower than readState.Index' (duration: 47.317672ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:26:30.734561Z","caller":"traceutil/trace.go:171","msg":"trace[2014185637] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"320.156722ms","start":"2026-04-13T17:26:30.414368Z","end":"2026-04-13T17:26:30.734525Z","steps":["trace[2014185637] 'process raft request' (duration: 319.861353ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:30.734697Z","caller":"traceutil/trace.go:171","msg":"trace[189242133] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"320.239946ms","start":"2026-04-13T17:26:30.414417Z","end":"2026-04-13T17:26:30.734657Z","steps":["trace[189242133] 'process raft request' (duration: 319.985618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.734754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:30.414361Z","time spent":"320.273566ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:30.734769Z","caller":"traceutil/trace.go:171","msg":"trace[1781604019] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"320.331618ms","start":"2026-04-13T17:26:30.414414Z","end":"2026-04-13T17:26:30.734745Z","steps":["trace[1781604019] 'process raft request' (duration: 320.032889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.734815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:30.414401Z","time spent":"320.334088ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:26:30.734877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:30.414399Z","time spent":"320.420441ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:30.734924Z","caller":"traceutil/trace.go:171","msg":"trace[694310295] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"319.924026ms","start":"2026-04-13T17:26:30.414987Z","end":"2026-04-13T17:26:30.734911Z","steps":["trace[694310295] 'process raft request' (duration: 319.489312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.734974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.272284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:26:30.734989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:30.414975Z","time spent":"319.976807ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:30.735034Z","caller":"traceutil/trace.go:171","msg":"trace[1527174114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8412; }","duration":"194.575913ms","start":"2026-04-13T17:26:30.540444Z","end":"2026-04-13T17:26:30.73502Z","steps":["trace[1527174114] 'agreement among raft nodes before linearized reading' (duration: 194.267493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:30.735363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.240347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-13T17:26:30.735427Z","caller":"traceutil/trace.go:171","msg":"trace[165848199] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8412; }","duration":"164.35369ms","start":"2026-04-13T17:26:30.571057Z","end":"2026-04-13T17:26:30.73541Z","steps":["trace[165848199] 'agreement among raft nodes before linearized reading' (duration: 163.917347ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:39.770485Z","caller":"traceutil/trace.go:171","msg":"trace[110204363] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"121.428248ms","start":"2026-04-13T17:26:39.649023Z","end":"2026-04-13T17:26:39.770451Z","steps":["trace[110204363] 'process raft request' (duration: 87.329073ms)","trace[110204363] 'compare' (duration: 33.931829ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:26:39.791121Z","caller":"traceutil/trace.go:171","msg":"trace[1573078056] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8497; }","duration":"141.395258ms","start":"2026-04-13T17:26:39.649703Z","end":"2026-04-13T17:26:39.791098Z","steps":["trace[1573078056] 'process raft request' (duration: 141.094538ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:40.407409Z","caller":"traceutil/trace.go:171","msg":"trace[807735940] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"165.866949ms","start":"2026-04-13T17:26:40.241513Z","end":"2026-04-13T17:26:40.40738Z","steps":["trace[807735940] 'process raft request' (duration: 165.358803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.006965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.981694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:26:41.007104Z","caller":"traceutil/trace.go:171","msg":"trace[869810044] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"562.537354ms","start":"2026-04-13T17:26:40.444552Z","end":"2026-04-13T17:26:41.007089Z","steps":["trace[869810044] 'process raft request' (duration: 244.373174ms)","trace[869810044] 'compare' (duration: 317.672515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:26:41.007159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.444524Z","time spent":"562.613187ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:41.063063Z","caller":"traceutil/trace.go:171","msg":"trace[1878942472] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"591.732775ms","start":"2026-04-13T17:26:40.471302Z","end":"2026-04-13T17:26:41.063035Z","steps":["trace[1878942472] 'process raft request' (duration: 591.426696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.063247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.471287Z","time spent":"591.859819ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:41.06341Z","caller":"traceutil/trace.go:171","msg":"trace[1567068680] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"550.956549ms","start":"2026-04-13T17:26:40.512422Z","end":"2026-04-13T17:26:41.063379Z","steps":["trace[1567068680] 'process raft request' (duration: 550.497405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.063571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.512394Z","time spent":"551.098724ms","remote":"127.0.0.1:52170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:26:41.063556Z","caller":"traceutil/trace.go:171","msg":"trace[1574543105] linearizableReadLoop","detail":"{readStateIndex:8809; appliedIndex:8806; }","duration":"522.646767ms","start":"2026-04-13T17:26:40.540886Z","end":"2026-04-13T17:26:41.063532Z","steps":["trace[1574543105] 'read index received' (duration: 148.152561ms)","trace[1574543105] 'applied index is now lower than readState.Index' (duration: 374.492806ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:26:41.063689Z","caller":"traceutil/trace.go:171","msg":"trace[1927613687] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"521.951635ms","start":"2026-04-13T17:26:40.541703Z","end":"2026-04-13T17:26:41.063655Z","steps":["trace[1927613687] 'process raft request' (duration: 521.515801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.063775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.870224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-s7k7z\" ","response":"range_response_count:1 size:4653"} {"level":"warn","ts":"2026-04-13T17:26:41.063825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.541692Z","time spent":"522.066508ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:26:41.063829Z","caller":"traceutil/trace.go:171","msg":"trace[618718508] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-s7k7z; range_end:; response_count:1; response_revision:8504; }","duration":"522.965877ms","start":"2026-04-13T17:26:40.54085Z","end":"2026-04-13T17:26:41.063816Z","steps":["trace[618718508] 'agreement among raft nodes before linearized reading' (duration: 522.75355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.063969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.540839Z","time spent":"523.058319ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4677,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-s7k7z\" "} {"level":"info","ts":"2026-04-13T17:26:41.348121Z","caller":"traceutil/trace.go:171","msg":"trace[1172916978] linearizableReadLoop","detail":"{readStateIndex:8811; appliedIndex:8810; }","duration":"284.365424ms","start":"2026-04-13T17:26:41.063732Z","end":"2026-04-13T17:26:41.348098Z","steps":["trace[1172916978] 'read index received' (duration: 284.189548ms)","trace[1172916978] 'applied index is now lower than readState.Index' (duration: 174.966µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:26:41.348232Z","caller":"traceutil/trace.go:171","msg":"trace[289751213] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"367.871896ms","start":"2026-04-13T17:26:40.980335Z","end":"2026-04-13T17:26:41.348207Z","steps":["trace[289751213] 'process raft request' (duration: 367.560377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.348357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.980314Z","time spent":"367.97135ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:26:41.348472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.441116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T17:26:41.34853Z","caller":"traceutil/trace.go:171","msg":"trace[636354488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8505; }","duration":"566.518088ms","start":"2026-04-13T17:26:40.781999Z","end":"2026-04-13T17:26:41.348517Z","steps":["trace[636354488] 'agreement among raft nodes before linearized reading' (duration: 566.421035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.348559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.781988Z","time spent":"566.56674ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T17:26:41.348472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.716036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rgdx4\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-13T17:26:41.34863Z","caller":"traceutil/trace.go:171","msg":"trace[609707369] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rgdx4; range_end:; response_count:1; response_revision:8505; }","duration":"370.957883ms","start":"2026-04-13T17:26:40.977659Z","end":"2026-04-13T17:26:41.348617Z","steps":["trace[609707369] 'agreement among raft nodes before linearized reading' (duration: 370.656564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.348672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.977632Z","time spent":"371.029206ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-rgdx4\" "} {"level":"warn","ts":"2026-04-13T17:26:41.348663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.840755ms","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-13T17:26:41.348668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.564792ms","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-13T17:26:41.348743Z","caller":"traceutil/trace.go:171","msg":"trace[508059728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8505; }","duration":"583.975089ms","start":"2026-04-13T17:26:40.764749Z","end":"2026-04-13T17:26:41.348725Z","steps":["trace[508059728] 'agreement among raft nodes before linearized reading' (duration: 583.713641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.348747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.135065ms","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-13T17:26:41.348798Z","caller":"traceutil/trace.go:171","msg":"trace[1322954420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8505; }","duration":"567.727236ms","start":"2026-04-13T17:26:40.78105Z","end":"2026-04-13T17:26:41.348778Z","steps":["trace[1322954420] 'agreement among raft nodes before linearized reading' (duration: 567.21299ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:41.348828Z","caller":"traceutil/trace.go:171","msg":"trace[834578773] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8505; }","duration":"565.262079ms","start":"2026-04-13T17:26:40.783544Z","end":"2026-04-13T17:26:41.348806Z","steps":["trace[834578773] 'agreement among raft nodes before linearized reading' (duration: 564.9778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.3485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.966539ms","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-13T17:26:41.348881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.783526Z","time spent":"565.346701ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-13T17:26:41.348883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.764729Z","time spent":"584.080412ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:26:41.348903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.781039Z","time spent":"567.82816ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:26:41.348943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.279718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-13T17:26:41.348991Z","caller":"traceutil/trace.go:171","msg":"trace[2132518994] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8505; }","duration":"767.34694ms","start":"2026-04-13T17:26:40.581633Z","end":"2026-04-13T17:26:41.34898Z","steps":["trace[2132518994] 'agreement among raft nodes before linearized reading' (duration: 766.560225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.349029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.581626Z","time spent":"767.396222ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5170,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-04-13T17:26:41.348946Z","caller":"traceutil/trace.go:171","msg":"trace[1401359220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8505; }","duration":"565.410993ms","start":"2026-04-13T17:26:40.783521Z","end":"2026-04-13T17:26:41.348932Z","steps":["trace[1401359220] 'agreement among raft nodes before linearized reading' (duration: 564.632659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.349094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.807392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T17:26:41.349145Z","caller":"traceutil/trace.go:171","msg":"trace[1787855422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8505; }","duration":"569.892226ms","start":"2026-04-13T17:26:40.779241Z","end":"2026-04-13T17:26:41.349133Z","steps":["trace[1787855422] 'agreement among raft nodes before linearized reading' (duration: 569.031469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:41.34918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.779222Z","time spent":"569.949017ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T17:26:41.349219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:40.78351Z","time spent":"565.624461ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T17:26:42.323333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.153588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:26:42.323422Z","caller":"traceutil/trace.go:171","msg":"trace[839814839] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8514; }","duration":"146.294432ms","start":"2026-04-13T17:26:42.177113Z","end":"2026-04-13T17:26:42.323407Z","steps":["trace[839814839] 'count revisions from in-memory index tree' (duration: 146.043604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:42.323428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.472275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-13T17:26:42.323496Z","caller":"traceutil/trace.go:171","msg":"trace[1740755207] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8514; }","duration":"295.554747ms","start":"2026-04-13T17:26:42.027922Z","end":"2026-04-13T17:26:42.323477Z","steps":["trace[1740755207] 'range keys from in-memory index tree' (duration: 295.267999ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:42.798135Z","caller":"traceutil/trace.go:171","msg":"trace[1401318794] transaction","detail":"{read_only:false; response_revision:8516; number_of_response:1; }","duration":"163.951948ms","start":"2026-04-13T17:26:42.63416Z","end":"2026-04-13T17:26:42.798112Z","steps":["trace[1401318794] 'process raft request' (duration: 163.775103ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:43.173081Z","caller":"traceutil/trace.go:171","msg":"trace[1618992220] transaction","detail":"{read_only:false; response_revision:8518; number_of_response:1; }","duration":"103.42352ms","start":"2026-04-13T17:26:43.069628Z","end":"2026-04-13T17:26:43.173052Z","steps":["trace[1618992220] 'process raft request' (duration: 56.797251ms)","trace[1618992220] 'compare' (duration: 46.114563ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:27:30.790747Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002464.wal"} {"level":"warn","ts":"2026-04-13T17:27:43.646327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.807267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:27:43.646469Z","caller":"traceutil/trace.go:171","msg":"trace[1799935400] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:9172; }","duration":"120.020174ms","start":"2026-04-13T17:27:43.526426Z","end":"2026-04-13T17:27:43.646446Z","steps":["trace[1799935400] 'count revisions from in-memory index tree' (duration: 119.705423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:27:43.646439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.418003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-13T17:27:43.646541Z","caller":"traceutil/trace.go:171","msg":"trace[1437725869] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9172; }","duration":"216.510606ms","start":"2026-04-13T17:27:43.430009Z","end":"2026-04-13T17:27:43.64652Z","steps":["trace[1437725869] 'range keys from in-memory index tree' (duration: 216.238257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:27:43.646333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.732903ms","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-13T17:27:43.647127Z","caller":"traceutil/trace.go:171","msg":"trace[339152066] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:9172; }","duration":"171.60538ms","start":"2026-04-13T17:27:43.475508Z","end":"2026-04-13T17:27:43.647114Z","steps":["trace[339152066] 'count revisions from in-memory index tree' (duration: 170.552696ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:27:55.413678Z","caller":"traceutil/trace.go:171","msg":"trace[1909147330] transaction","detail":"{read_only:false; response_revision:9240; number_of_response:1; }","duration":"151.215537ms","start":"2026-04-13T17:27:55.262433Z","end":"2026-04-13T17:27:55.413649Z","steps":["trace[1909147330] 'process raft request' (duration: 151.051602ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:27:56.257105Z","caller":"traceutil/trace.go:171","msg":"trace[1061152816] transaction","detail":"{read_only:false; response_revision:9246; number_of_response:1; }","duration":"413.813816ms","start":"2026-04-13T17:27:55.843267Z","end":"2026-04-13T17:27:56.257081Z","steps":["trace[1061152816] 'process raft request' (duration: 413.755884ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:27:56.257145Z","caller":"traceutil/trace.go:171","msg":"trace[977430947] transaction","detail":"{read_only:false; response_revision:9245; number_of_response:1; }","duration":"413.885508ms","start":"2026-04-13T17:27:55.843243Z","end":"2026-04-13T17:27:56.257128Z","steps":["trace[977430947] 'process raft request' (duration: 413.751034ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:27:56.257236Z","caller":"traceutil/trace.go:171","msg":"trace[1592067941] linearizableReadLoop","detail":"{readStateIndex:9572; appliedIndex:9568; }","duration":"413.889358ms","start":"2026-04-13T17:27:55.843319Z","end":"2026-04-13T17:27:56.257209Z","steps":["trace[1592067941] 'read index received' (duration: 73.835808ms)","trace[1592067941] 'applied index is now lower than readState.Index' (duration: 340.05116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:27:56.257258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:27:55.843246Z","time spent":"413.93933ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:27:56.257278Z","caller":"traceutil/trace.go:171","msg":"trace[1550697983] transaction","detail":"{read_only:false; response_revision:9247; number_of_response:1; }","duration":"413.829136ms","start":"2026-04-13T17:27:55.843425Z","end":"2026-04-13T17:27:56.257254Z","steps":["trace[1550697983] 'process raft request' (duration: 413.62423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:27:56.257257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:27:55.843223Z","time spent":"413.9644ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:27:56.257121Z","caller":"traceutil/trace.go:171","msg":"trace[434977234] transaction","detail":"{read_only:false; response_revision:9244; number_of_response:1; }","duration":"413.888368ms","start":"2026-04-13T17:27:55.843198Z","end":"2026-04-13T17:27:56.257087Z","steps":["trace[434977234] 'process raft request' (duration: 413.64504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:27:56.257379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:27:55.843415Z","time spent":"413.905328ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:27:56.257441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:27:55.843181Z","time spent":"414.217709ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:27:56.25775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.418564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291246"} {"level":"info","ts":"2026-04-13T17:27:56.257816Z","caller":"traceutil/trace.go:171","msg":"trace[133906595] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9247; }","duration":"414.956001ms","start":"2026-04-13T17:27:55.842841Z","end":"2026-04-13T17:27:56.257797Z","steps":["trace[133906595] 'agreement among raft nodes before linearized reading' (duration: 414.443815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:27:56.257867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:27:55.842813Z","time spent":"415.039534ms","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":31,"response size":291270,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-04-13T17:27:56.570371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.607297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:27:56.570467Z","caller":"traceutil/trace.go:171","msg":"trace[1250262224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9247; }","duration":"222.762581ms","start":"2026-04-13T17:27:56.347674Z","end":"2026-04-13T17:27:56.570437Z","steps":["trace[1250262224] 'range keys from in-memory index tree' (duration: 222.521384ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:27:56.583796Z","caller":"traceutil/trace.go:171","msg":"trace[1426065733] transaction","detail":"{read_only:false; response_revision:9248; number_of_response:1; }","duration":"164.166144ms","start":"2026-04-13T17:27:56.419597Z","end":"2026-04-13T17:27:56.583763Z","steps":["trace[1426065733] 'process raft request' (duration: 163.869505ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:28:53.623705Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ad427454a89beddc","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-13T17:28:53.631045Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-13T17:28:53.631144Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-13T17:29:50.349605Z","caller":"traceutil/trace.go:171","msg":"trace[867029432] transaction","detail":"{read_only:false; response_revision:10058; number_of_response:1; }","duration":"226.62755ms","start":"2026-04-13T17:29:50.122957Z","end":"2026-04-13T17:29:50.349584Z","steps":["trace[867029432] 'process raft request' (duration: 226.381563ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:30:12.041999Z","caller":"traceutil/trace.go:171","msg":"trace[1489261369] transaction","detail":"{read_only:false; response_revision:10272; number_of_response:1; }","duration":"330.934651ms","start":"2026-04-13T17:30:11.711007Z","end":"2026-04-13T17:30:12.041942Z","steps":["trace[1489261369] 'process raft request' (duration: 329.357491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:30:12.042203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:30:11.710986Z","time spent":"331.130337ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:30:12.042243Z","caller":"traceutil/trace.go:171","msg":"trace[2140309767] transaction","detail":"{read_only:false; response_revision:10273; number_of_response:1; }","duration":"161.03305ms","start":"2026-04-13T17:30:11.880891Z","end":"2026-04-13T17:30:12.041924Z","steps":["trace[2140309767] 'process raft request' (duration: 160.998429ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:31:15.009755Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8277} {"level":"info","ts":"2026-04-13T17:31:15.095473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8277,"took":"82.58327ms","hash":1961396557,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":24801280,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-13T17:31:15.09554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1961396557,"revision":8277,"compact-revision":6107} {"level":"warn","ts":"2026-04-13T17:32:25.760703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.28684ms","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-13T17:32:25.76077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.208775ms","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-13T17:32:25.760805Z","caller":"traceutil/trace.go:171","msg":"trace[164788765] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11033; }","duration":"159.424765ms","start":"2026-04-13T17:32:25.601357Z","end":"2026-04-13T17:32:25.760782Z","steps":["trace[164788765] 'range keys from in-memory index tree' (duration: 159.162287ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:32:25.760857Z","caller":"traceutil/trace.go:171","msg":"trace[1549626021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11033; }","duration":"181.310709ms","start":"2026-04-13T17:32:25.579511Z","end":"2026-04-13T17:32:25.760821Z","steps":["trace[1549626021] 'range keys from in-memory index tree' (duration: 181.025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:32:25.760703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.640593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T17:32:25.761081Z","caller":"traceutil/trace.go:171","msg":"trace[1708861930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11033; }","duration":"167.054027ms","start":"2026-04-13T17:32:25.593992Z","end":"2026-04-13T17:32:25.761046Z","steps":["trace[1708861930] 'range keys from in-memory index tree' (duration: 166.442678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:32:25.761396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.692584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T17:32:25.761454Z","caller":"traceutil/trace.go:171","msg":"trace[66986596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11033; }","duration":"160.82448ms","start":"2026-04-13T17:32:25.600615Z","end":"2026-04-13T17:32:25.761439Z","steps":["trace[66986596] 'range keys from in-memory index tree' (duration: 160.494898ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:36:15.015432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10659} {"level":"info","ts":"2026-04-13T17:36:15.089843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10659,"took":"72.888311ms","hash":2412122116,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":20328448,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-13T17:36:15.089898Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2412122116,"revision":10659,"compact-revision":8277}