{"level":"warn","ts":"2026-04-17T00:34:05.118757Z","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-17T00:34:05.118857Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-17T00:34:05.118924Z","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-17T00:34:05.118933Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-04-17T00:34:05.11895Z","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-17T00:34:05.119571Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-04-17T00:34:05.119638Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-17T00:34:05.123988Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.049577ms"} {"level":"info","ts":"2026-04-17T00:34:05.133179Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-04-17T00:34:05.133289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T00:34:05.133321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-04-17T00:34:05.133353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T00:34:05.133367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-04-17T00:34:05.133424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-04-17T00:34:05.140884Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T00:34:05.14309Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T00:34:05.144528Z","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-17T00:34:05.147496Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T00:34:05.147683Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T00:34:05.147841Z","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-17T00:34:05.148402Z","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-17T00:34:05.148416Z","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-17T00:34:05.14946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-04-17T00:34:05.149671Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-04-17T00:34:05.152569Z","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-17T00:34:05.15282Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T00:34:05.152887Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T00:34:05.153002Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-04-17T00:34:05.153075Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-04-17T00:34:05.53549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T00:34:05.535533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T00:34:05.535548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-04-17T00:34:05.53556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-04-17T00:34:05.535566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-04-17T00:34:05.535574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-04-17T00:34:05.535581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-04-17T00:34:05.53876Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:34:05.541078Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T00:34:05.54139Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:34:05.541998Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T00:34:05.54212Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T00:34:05.542243Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T00:34:05.542983Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:34:05.543055Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:34:05.543075Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T00:34:05.544804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-04-17T00:34:05.545836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T00:34:22.687986Z","caller":"traceutil/trace.go:171","msg":"trace[1259591073] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"124.31314ms","start":"2026-04-17T00:34:22.563649Z","end":"2026-04-17T00:34:22.687962Z","steps":["trace[1259591073] 'process raft request' (duration: 124.191137ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:34.877228Z","caller":"traceutil/trace.go:171","msg":"trace[1751958821] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"113.978464ms","start":"2026-04-17T00:34:34.763222Z","end":"2026-04-17T00:34:34.8772Z","steps":["trace[1751958821] 'process raft request' (duration: 113.804699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:41.096151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.201392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:34:41.096279Z","caller":"traceutil/trace.go:171","msg":"trace[731556428] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"184.820078ms","start":"2026-04-17T00:34:40.911442Z","end":"2026-04-17T00:34:41.096263Z","steps":["trace[731556428] 'read index received' (duration: 56.969396ms)","trace[731556428] 'applied index is now lower than readState.Index' (duration: 127.849352ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:34:41.096307Z","caller":"traceutil/trace.go:171","msg":"trace[1113450794] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"297.909326ms","start":"2026-04-17T00:34:40.798381Z","end":"2026-04-17T00:34:41.09629Z","steps":["trace[1113450794] 'process raft request' (duration: 170.105025ms)","trace[1113450794] 'compare' (duration: 127.035288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:41.096437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.997773ms","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-17T00:34:41.096488Z","caller":"traceutil/trace.go:171","msg":"trace[644054122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"185.086295ms","start":"2026-04-17T00:34:40.911382Z","end":"2026-04-17T00:34:41.096468Z","steps":["trace[644054122] 'agreement among raft nodes before linearized reading' (duration: 184.92606ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:34:41.288747Z","caller":"traceutil/trace.go:171","msg":"trace[642173788] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"183.87589ms","start":"2026-04-17T00:34:41.104838Z","end":"2026-04-17T00:34:41.288714Z","steps":["trace[642173788] 'process raft request' (duration: 111.493913ms)","trace[642173788] 'compare' (duration: 72.226983ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:34:41.288939Z","caller":"traceutil/trace.go:171","msg":"trace[649206625] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"180.656548ms","start":"2026-04-17T00:34:41.108229Z","end":"2026-04-17T00:34:41.288886Z","steps":["trace[649206625] 'read index received' (duration: 108.161247ms)","trace[649206625] 'applied index is now lower than readState.Index' (duration: 72.487621ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:41.289064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.818803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:34:41.289161Z","caller":"traceutil/trace.go:171","msg":"trace[1382126777] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:621; }","duration":"180.960017ms","start":"2026-04-17T00:34:41.108176Z","end":"2026-04-17T00:34:41.289136Z","steps":["trace[1382126777] 'agreement among raft nodes before linearized reading' (duration: 180.825952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:41.787532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.554313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:34:41.787653Z","caller":"traceutil/trace.go:171","msg":"trace[22508537] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"320.034061ms","start":"2026-04-17T00:34:41.467597Z","end":"2026-04-17T00:34:41.787631Z","steps":["trace[22508537] 'read index received' (duration: 138.108387ms)","trace[22508537] 'applied index is now lower than readState.Index' (duration: 181.923604ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:34:41.787695Z","caller":"traceutil/trace.go:171","msg":"trace[521513614] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"451.330013ms","start":"2026-04-17T00:34:41.336328Z","end":"2026-04-17T00:34:41.787658Z","steps":["trace[521513614] 'process raft request' (duration: 269.435749ms)","trace[521513614] 'compare' (duration: 181.45003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:34:41.787819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.222127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:34:41.787868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:41.336305Z","time spent":"451.475086ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:34:41.787914Z","caller":"traceutil/trace.go:171","msg":"trace[1099529591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"320.390482ms","start":"2026-04-17T00:34:41.46751Z","end":"2026-04-17T00:34:41.787901Z","steps":["trace[1099529591] 'agreement among raft nodes before linearized reading' (duration: 320.211747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:41.788033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:34:41.467483Z","time spent":"320.537426ms","remote":"127.0.0.1:35140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T00:34:41.804615Z","caller":"traceutil/trace.go:171","msg":"trace[1959945863] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"133.866665ms","start":"2026-04-17T00:34:41.670712Z","end":"2026-04-17T00:34:41.804579Z","steps":["trace[1959945863] 'process raft request' (duration: 131.056764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:34:41.805234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.075908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p6mt7\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-17T00:34:41.805341Z","caller":"traceutil/trace.go:171","msg":"trace[106049974] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p6mt7; range_end:; response_count:1; response_revision:624; }","duration":"253.206842ms","start":"2026-04-17T00:34:41.552091Z","end":"2026-04-17T00:34:41.805297Z","steps":["trace[106049974] 'agreement among raft nodes before linearized reading' (duration: 252.404439ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:08.032587Z","caller":"traceutil/trace.go:171","msg":"trace[1380322955] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"301.359172ms","start":"2026-04-17T00:36:07.731179Z","end":"2026-04-17T00:36:08.032538Z","steps":["trace[1380322955] 'process raft request' (duration: 226.485062ms)","trace[1380322955] 'compare' (duration: 74.631473ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:08.03268Z","caller":"traceutil/trace.go:171","msg":"trace[612480784] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"299.278465ms","start":"2026-04-17T00:36:07.733325Z","end":"2026-04-17T00:36:08.032603Z","steps":["trace[612480784] 'process raft request' (duration: 299.129741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:08.032842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:07.731164Z","time spent":"301.543438ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:36:08.370505Z","caller":"traceutil/trace.go:171","msg":"trace[1637406575] linearizableReadLoop","detail":"{readStateIndex:1883; appliedIndex:1882; }","duration":"259.310415ms","start":"2026-04-17T00:36:08.111165Z","end":"2026-04-17T00:36:08.370476Z","steps":["trace[1637406575] 'read index received' (duration: 259.231273ms)","trace[1637406575] 'applied index is now lower than readState.Index' (duration: 77.442µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:36:08.370657Z","caller":"traceutil/trace.go:171","msg":"trace[1278184994] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"330.210094ms","start":"2026-04-17T00:36:08.040432Z","end":"2026-04-17T00:36:08.370642Z","steps":["trace[1278184994] 'process raft request' (duration: 329.861474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:08.370801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:36:08.040405Z","time spent":"330.283836ms","remote":"127.0.0.1:35352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:36:08.371022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.856039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4de10359-e0c5-4a45-a1cc-7a49e9ce1d71\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-17T00:36:08.371105Z","caller":"traceutil/trace.go:171","msg":"trace[107461921] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4de10359-e0c5-4a45-a1cc-7a49e9ce1d71; range_end:; response_count:1; response_revision:1827; }","duration":"259.979453ms","start":"2026-04-17T00:36:08.111111Z","end":"2026-04-17T00:36:08.371091Z","steps":["trace[107461921] 'agreement among raft nodes before linearized reading' (duration: 259.866569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:08.371375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.331676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-17T00:36:08.371463Z","caller":"traceutil/trace.go:171","msg":"trace[1461154660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1827; }","duration":"173.446429ms","start":"2026-04-17T00:36:08.197985Z","end":"2026-04-17T00:36:08.371431Z","steps":["trace[1461154660] 'agreement among raft nodes before linearized reading' (duration: 173.206422ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:36:13.483836Z","caller":"traceutil/trace.go:171","msg":"trace[549232817] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"100.278466ms","start":"2026-04-17T00:36:13.383531Z","end":"2026-04-17T00:36:13.48381Z","steps":["trace[549232817] 'process raft request' (duration: 100.0515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:50.109879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.155431ms","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-17T00:36:50.110194Z","caller":"traceutil/trace.go:171","msg":"trace[1227710473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2163; }","duration":"159.524882ms","start":"2026-04-17T00:36:49.950647Z","end":"2026-04-17T00:36:50.110172Z","steps":["trace[1227710473] 'range keys from in-memory index tree' (duration: 158.851483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:50.110249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.580948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-17T00:36:50.110345Z","caller":"traceutil/trace.go:171","msg":"trace[60420854] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2163; }","duration":"170.749574ms","start":"2026-04-17T00:36:49.939571Z","end":"2026-04-17T00:36:50.11032Z","steps":["trace[60420854] 'range keys from in-memory index tree' (duration: 170.187058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:36:50.380714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.888781ms","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-17T00:36:50.380859Z","caller":"traceutil/trace.go:171","msg":"trace[1112678104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2164; }","duration":"111.112698ms","start":"2026-04-17T00:36:50.269715Z","end":"2026-04-17T00:36:50.380828Z","steps":["trace[1112678104] 'range keys from in-memory index tree' (duration: 110.629594ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:38:02.637401Z","caller":"traceutil/trace.go:171","msg":"trace[901918757] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"111.138248ms","start":"2026-04-17T00:38:02.526235Z","end":"2026-04-17T00:38:02.637373Z","steps":["trace[901918757] 'process raft request' (duration: 110.988834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:40:21.577252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.219462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:40:21.577333Z","caller":"traceutil/trace.go:171","msg":"trace[2041837732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4512; }","duration":"109.305075ms","start":"2026-04-17T00:40:21.468006Z","end":"2026-04-17T00:40:21.577311Z","steps":["trace[2041837732] 'range keys from in-memory index tree' (duration: 109.16004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:40:21.577907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.657906ms","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-17T00:40:21.577989Z","caller":"traceutil/trace.go:171","msg":"trace[976514201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4512; }","duration":"229.80367ms","start":"2026-04-17T00:40:21.348169Z","end":"2026-04-17T00:40:21.577973Z","steps":["trace[976514201] 'range keys from in-memory index tree' (duration: 229.44394ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:40:27.322389Z","caller":"traceutil/trace.go:171","msg":"trace[1813601327] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"124.888047ms","start":"2026-04-17T00:40:27.197452Z","end":"2026-04-17T00:40:27.32234Z","steps":["trace[1813601327] 'process raft request' (duration: 95.010057ms)","trace[1813601327] 'compare' (duration: 29.690255ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:40:27.322459Z","caller":"traceutil/trace.go:171","msg":"trace[1507107168] linearizableReadLoop","detail":"{readStateIndex:4844; appliedIndex:4843; }","duration":"115.745824ms","start":"2026-04-17T00:40:27.206666Z","end":"2026-04-17T00:40:27.322411Z","steps":["trace[1507107168] 'read index received' (duration: 86.868012ms)","trace[1507107168] 'applied index is now lower than readState.Index' (duration: 28.868802ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:40:27.322868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.131384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-04-17T00:40:27.322909Z","caller":"traceutil/trace.go:171","msg":"trace[1954820388] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4649; }","duration":"116.272328ms","start":"2026-04-17T00:40:27.206622Z","end":"2026-04-17T00:40:27.322895Z","steps":["trace[1954820388] 'agreement among raft nodes before linearized reading' (duration: 116.012431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:40:27.322924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.035722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:13 size:97272"} {"level":"info","ts":"2026-04-17T00:40:27.322963Z","caller":"traceutil/trace.go:171","msg":"trace[1640599405] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:13; response_revision:4649; }","duration":"116.176136ms","start":"2026-04-17T00:40:27.206778Z","end":"2026-04-17T00:40:27.322954Z","steps":["trace[1640599405] 'agreement among raft nodes before linearized reading' (duration: 115.925929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:40:34.027744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.864384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:40:34.027888Z","caller":"traceutil/trace.go:171","msg":"trace[1586759252] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4759; }","duration":"170.088921ms","start":"2026-04-17T00:40:33.857772Z","end":"2026-04-17T00:40:34.027861Z","steps":["trace[1586759252] 'count revisions from in-memory index tree' (duration: 169.757172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:40:34.027771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.153891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:40:34.028013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.479704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-17T00:40:34.028032Z","caller":"traceutil/trace.go:171","msg":"trace[903714380] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:4759; }","duration":"220.4827ms","start":"2026-04-17T00:40:33.807504Z","end":"2026-04-17T00:40:34.027987Z","steps":["trace[903714380] 'count revisions from in-memory index tree' (duration: 220.032348ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:40:34.028136Z","caller":"traceutil/trace.go:171","msg":"trace[843283586] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4759; }","duration":"169.635579ms","start":"2026-04-17T00:40:33.858468Z","end":"2026-04-17T00:40:34.028103Z","steps":["trace[843283586] 'range keys from in-memory index tree' (duration: 169.29981ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:40:34.185341Z","caller":"traceutil/trace.go:171","msg":"trace[1321878564] linearizableReadLoop","detail":"{readStateIndex:4959; appliedIndex:4958; }","duration":"226.940231ms","start":"2026-04-17T00:40:33.958372Z","end":"2026-04-17T00:40:34.185312Z","steps":["trace[1321878564] 'read index received' (duration: 226.898549ms)","trace[1321878564] 'applied index is now lower than readState.Index' (duration: 40.641µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:40:34.185529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.134045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T00:40:34.185572Z","caller":"traceutil/trace.go:171","msg":"trace[1679734622] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4760; }","duration":"227.25493ms","start":"2026-04-17T00:40:33.958307Z","end":"2026-04-17T00:40:34.185562Z","steps":["trace[1679734622] 'agreement among raft nodes before linearized reading' (duration: 227.145546ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:40:34.185547Z","caller":"traceutil/trace.go:171","msg":"trace[1585040249] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"321.111775ms","start":"2026-04-17T00:40:33.86435Z","end":"2026-04-17T00:40:34.185462Z","steps":["trace[1585040249] 'process raft request' (duration: 320.783216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:40:34.18626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:40:33.864328Z","time spent":"321.403543ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:40:34.186355Z","caller":"traceutil/trace.go:171","msg":"trace[988101933] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"116.150975ms","start":"2026-04-17T00:40:34.070195Z","end":"2026-04-17T00:40:34.186346Z","steps":["trace[988101933] 'process raft request' (duration: 115.933629ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:40:34.186291Z","caller":"traceutil/trace.go:171","msg":"trace[551721117] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"151.29703ms","start":"2026-04-17T00:40:34.034965Z","end":"2026-04-17T00:40:34.186262Z","steps":["trace[551721117] 'process raft request' (duration: 151.043544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:40:34.189246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.436841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-17T00:40:34.189318Z","caller":"traceutil/trace.go:171","msg":"trace[1396467908] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4762; }","duration":"157.537674ms","start":"2026-04-17T00:40:34.031766Z","end":"2026-04-17T00:40:34.189304Z","steps":["trace[1396467908] 'agreement among raft nodes before linearized reading' (duration: 157.335518ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:38.553501Z","caller":"traceutil/trace.go:171","msg":"trace[1691079583] transaction","detail":"{read_only:false; response_revision:6038; number_of_response:1; }","duration":"275.667196ms","start":"2026-04-17T00:42:38.27781Z","end":"2026-04-17T00:42:38.553477Z","steps":["trace[1691079583] 'process raft request' (duration: 275.518622ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:42:38.591722Z","caller":"traceutil/trace.go:171","msg":"trace[1882482368] linearizableReadLoop","detail":"{readStateIndex:6293; appliedIndex:6291; }","duration":"176.358743ms","start":"2026-04-17T00:42:38.415337Z","end":"2026-04-17T00:42:38.591695Z","steps":["trace[1882482368] 'read index received' (duration: 138.11517ms)","trace[1882482368] 'applied index is now lower than readState.Index' (duration: 38.242383ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:42:38.591849Z","caller":"traceutil/trace.go:171","msg":"trace[1665001320] transaction","detail":"{read_only:false; response_revision:6039; number_of_response:1; }","duration":"311.44154ms","start":"2026-04-17T00:42:38.28038Z","end":"2026-04-17T00:42:38.591822Z","steps":["trace[1665001320] 'process raft request' (duration: 311.161122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:38.591991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.62599ms","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-17T00:42:38.592053Z","caller":"traceutil/trace.go:171","msg":"trace[1865175990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6039; }","duration":"176.735654ms","start":"2026-04-17T00:42:38.415302Z","end":"2026-04-17T00:42:38.592038Z","steps":["trace[1865175990] 'agreement among raft nodes before linearized reading' (duration: 176.538148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:38.592115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:42:38.280357Z","time spent":"311.555493ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T00:42:38.592211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.735734ms","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-17T00:42:38.592335Z","caller":"traceutil/trace.go:171","msg":"trace[2146458707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6039; }","duration":"176.923789ms","start":"2026-04-17T00:42:38.415385Z","end":"2026-04-17T00:42:38.592309Z","steps":["trace[2146458707] 'agreement among raft nodes before linearized reading' (duration: 176.558979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:42:38.592508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.060294ms","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-17T00:42:38.592624Z","caller":"traceutil/trace.go:171","msg":"trace[118313225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6039; }","duration":"177.215638ms","start":"2026-04-17T00:42:38.415384Z","end":"2026-04-17T00:42:38.5926Z","steps":["trace[118313225] 'agreement among raft nodes before linearized reading' (duration: 176.553919ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:44:05.576414Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3315} {"level":"info","ts":"2026-04-17T00:44:05.657009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3315,"took":"79.46897ms","hash":3817199028,"current-db-size-bytes":60215296,"current-db-size":"60 MB","current-db-size-in-use-bytes":34037760,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-17T00:44:05.657061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3817199028,"revision":3315,"compact-revision":-1} {"level":"info","ts":"2026-04-17T00:46:30.513222Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002113.wal"} {"level":"info","ts":"2026-04-17T00:46:34.720068Z","caller":"traceutil/trace.go:171","msg":"trace[947740780] transaction","detail":"{read_only:false; response_revision:8140; number_of_response:1; }","duration":"136.266473ms","start":"2026-04-17T00:46:34.583768Z","end":"2026-04-17T00:46:34.720034Z","steps":["trace[947740780] 'process raft request' (duration: 136.061217ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:45.681613Z","caller":"traceutil/trace.go:171","msg":"trace[821130395] transaction","detail":"{read_only:false; response_revision:8206; number_of_response:1; }","duration":"107.515494ms","start":"2026-04-17T00:46:45.574067Z","end":"2026-04-17T00:46:45.681583Z","steps":["trace[821130395] 'process raft request' (duration: 107.300088ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:46.015129Z","caller":"traceutil/trace.go:171","msg":"trace[374814970] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"103.297547ms","start":"2026-04-17T00:46:45.911798Z","end":"2026-04-17T00:46:46.015096Z","steps":["trace[374814970] 'process raft request' (duration: 60.827519ms)","trace[374814970] 'compare' (duration: 41.970524ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:46:46.244253Z","caller":"traceutil/trace.go:171","msg":"trace[1118977835] transaction","detail":"{read_only:false; response_revision:8214; number_of_response:1; }","duration":"127.961693ms","start":"2026-04-17T00:46:46.116258Z","end":"2026-04-17T00:46:46.24422Z","steps":["trace[1118977835] 'process raft request' (duration: 71.369072ms)","trace[1118977835] 'compare' (duration: 56.305993ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:46:47.891934Z","caller":"traceutil/trace.go:171","msg":"trace[460993770] linearizableReadLoop","detail":"{readStateIndex:8588; appliedIndex:8587; }","duration":"193.525102ms","start":"2026-04-17T00:46:47.698377Z","end":"2026-04-17T00:46:47.891902Z","steps":["trace[460993770] 'read index received' (duration: 193.410019ms)","trace[460993770] 'applied index is now lower than readState.Index' (duration: 113.613µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:46:47.89214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.712097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:47.892093Z","caller":"traceutil/trace.go:171","msg":"trace[1051600430] transaction","detail":"{read_only:false; response_revision:8221; number_of_response:1; }","duration":"203.423718ms","start":"2026-04-17T00:46:47.688618Z","end":"2026-04-17T00:46:47.892042Z","steps":["trace[1051600430] 'process raft request' (duration: 203.13796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.892155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.745938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:46:47.892206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.087448ms","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":"info","ts":"2026-04-17T00:46:47.892228Z","caller":"traceutil/trace.go:171","msg":"trace[989746399] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8221; }","duration":"158.114519ms","start":"2026-04-17T00:46:47.734106Z","end":"2026-04-17T00:46:47.892221Z","steps":["trace[989746399] 'agreement among raft nodes before linearized reading' (duration: 158.078648ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:47.89224Z","caller":"traceutil/trace.go:171","msg":"trace[1458664706] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8221; }","duration":"193.879671ms","start":"2026-04-17T00:46:47.698337Z","end":"2026-04-17T00:46:47.892217Z","steps":["trace[1458664706] 'agreement among raft nodes before linearized reading' (duration: 193.706397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.892264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.070472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-17T00:46:47.892303Z","caller":"traceutil/trace.go:171","msg":"trace[2046821984] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8221; }","duration":"129.169886ms","start":"2026-04-17T00:46:47.763122Z","end":"2026-04-17T00:46:47.892292Z","steps":["trace[2046821984] 'agreement among raft nodes before linearized reading' (duration: 129.029302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.892322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.2853ms","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":"info","ts":"2026-04-17T00:46:47.892353Z","caller":"traceutil/trace.go:171","msg":"trace[432655098] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8221; }","duration":"178.337081ms","start":"2026-04-17T00:46:47.714007Z","end":"2026-04-17T00:46:47.892344Z","steps":["trace[432655098] 'agreement among raft nodes before linearized reading' (duration: 178.275349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.892519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.415607ms","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-17T00:46:47.892544Z","caller":"traceutil/trace.go:171","msg":"trace[6878049] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8221; }","duration":"158.444027ms","start":"2026-04-17T00:46:47.73409Z","end":"2026-04-17T00:46:47.892534Z","steps":["trace[6878049] 'agreement among raft nodes before linearized reading' (duration: 158.409936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.89257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.356545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:47.892605Z","caller":"traceutil/trace.go:171","msg":"trace[977818948] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8221; }","duration":"123.428537ms","start":"2026-04-17T00:46:47.769165Z","end":"2026-04-17T00:46:47.892593Z","steps":["trace[977818948] 'agreement among raft nodes before linearized reading' (duration: 123.369535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.892662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.589811ms","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-17T00:46:47.89268Z","caller":"traceutil/trace.go:171","msg":"trace[1110160677] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8221; }","duration":"158.626953ms","start":"2026-04-17T00:46:47.734048Z","end":"2026-04-17T00:46:47.892675Z","steps":["trace[1110160677] 'agreement among raft nodes before linearized reading' (duration: 158.598082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.892785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.742442ms","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-17T00:46:47.892803Z","caller":"traceutil/trace.go:171","msg":"trace[1191722641] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8221; }","duration":"178.769162ms","start":"2026-04-17T00:46:47.714028Z","end":"2026-04-17T00:46:47.892797Z","steps":["trace[1191722641] 'agreement among raft nodes before linearized reading' (duration: 178.735041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.892813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.599971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:47.892844Z","caller":"traceutil/trace.go:171","msg":"trace[38935659] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8221; }","duration":"123.676923ms","start":"2026-04-17T00:46:47.769158Z","end":"2026-04-17T00:46:47.892835Z","steps":["trace[38935659] 'agreement among raft nodes before linearized reading' (duration: 123.625572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.892848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.045491ms","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-17T00:46:47.892172Z","caller":"traceutil/trace.go:171","msg":"trace[1844526596] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8221; }","duration":"193.779629ms","start":"2026-04-17T00:46:47.698381Z","end":"2026-04-17T00:46:47.89216Z","steps":["trace[1844526596] 'agreement among raft nodes before linearized reading' (duration: 193.682606ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:47.892912Z","caller":"traceutil/trace.go:171","msg":"trace[478534523] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:8221; }","duration":"179.137223ms","start":"2026-04-17T00:46:47.713748Z","end":"2026-04-17T00:46:47.892885Z","steps":["trace[478534523] 'agreement among raft nodes before linearized reading' (duration: 178.919627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.892997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.859678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:47.893033Z","caller":"traceutil/trace.go:171","msg":"trace[218880149] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8221; }","duration":"123.95088ms","start":"2026-04-17T00:46:47.769072Z","end":"2026-04-17T00:46:47.893022Z","steps":["trace[218880149] 'agreement among raft nodes before linearized reading' (duration: 123.891279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.893041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.047161ms","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-17T00:46:47.893088Z","caller":"traceutil/trace.go:171","msg":"trace[1344048460] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8221; }","duration":"179.106422ms","start":"2026-04-17T00:46:47.713968Z","end":"2026-04-17T00:46:47.893075Z","steps":["trace[1344048460] 'agreement among raft nodes before linearized reading' (duration: 179.0381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:47.893494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.994276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:47.893609Z","caller":"traceutil/trace.go:171","msg":"trace[5406126] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8221; }","duration":"194.14542ms","start":"2026-04-17T00:46:47.699435Z","end":"2026-04-17T00:46:47.89358Z","steps":["trace[5406126] 'agreement among raft nodes before linearized reading' (duration: 193.961235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.103835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.695198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T00:46:48.104027Z","caller":"traceutil/trace.go:171","msg":"trace[1568665682] linearizableReadLoop","detail":"{readStateIndex:8589; appliedIndex:8588; }","duration":"204.530128ms","start":"2026-04-17T00:46:47.899476Z","end":"2026-04-17T00:46:48.104007Z","steps":["trace[1568665682] 'read index received' (duration: 64.542682ms)","trace[1568665682] 'applied index is now lower than readState.Index' (duration: 139.986266ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:46:48.104205Z","caller":"traceutil/trace.go:171","msg":"trace[495014811] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"205.037502ms","start":"2026-04-17T00:46:47.899098Z","end":"2026-04-17T00:46:48.104135Z","steps":["trace[495014811] 'process raft request' (duration: 64.936942ms)","trace[495014811] 'compare' (duration: 139.541004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:46:48.104414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.879418ms","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":"info","ts":"2026-04-17T00:46:48.104528Z","caller":"traceutil/trace.go:171","msg":"trace[1324796436] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8222; }","duration":"205.027942ms","start":"2026-04-17T00:46:47.899474Z","end":"2026-04-17T00:46:48.104502Z","steps":["trace[1324796436] 'agreement among raft nodes before linearized reading' (duration: 204.774645ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:48.133757Z","caller":"traceutil/trace.go:171","msg":"trace[58279211] transaction","detail":"{read_only:false; response_revision:8224; number_of_response:1; }","duration":"230.884879ms","start":"2026-04-17T00:46:47.902853Z","end":"2026-04-17T00:46:48.133738Z","steps":["trace[58279211] 'process raft request' (duration: 230.804656ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:48.133821Z","caller":"traceutil/trace.go:171","msg":"trace[738645552] transaction","detail":"{read_only:false; response_revision:8223; number_of_response:1; }","duration":"233.432909ms","start":"2026-04-17T00:46:47.900334Z","end":"2026-04-17T00:46:48.133767Z","steps":["trace[738645552] 'process raft request' (duration: 233.144892ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:48.278209Z","caller":"traceutil/trace.go:171","msg":"trace[362571382] linearizableReadLoop","detail":"{readStateIndex:8592; appliedIndex:8589; }","duration":"174.127043ms","start":"2026-04-17T00:46:48.104052Z","end":"2026-04-17T00:46:48.278179Z","steps":["trace[362571382] 'read index received' (duration: 29.471178ms)","trace[362571382] 'applied index is now lower than readState.Index' (duration: 144.654535ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:46:48.278359Z","caller":"traceutil/trace.go:171","msg":"trace[1013076147] transaction","detail":"{read_only:false; response_revision:8225; number_of_response:1; }","duration":"257.059856ms","start":"2026-04-17T00:46:48.021284Z","end":"2026-04-17T00:46:48.278344Z","steps":["trace[1013076147] 'process raft request' (duration: 208.55926ms)","trace[1013076147] 'compare' (duration: 48.194138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T00:46:48.278565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.458544ms","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":"info","ts":"2026-04-17T00:46:48.278653Z","caller":"traceutil/trace.go:171","msg":"trace[557136335] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8225; }","duration":"321.605288ms","start":"2026-04-17T00:46:47.957028Z","end":"2026-04-17T00:46:48.278634Z","steps":["trace[557136335] 'agreement among raft nodes before linearized reading' (duration: 321.446284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.278717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.957006Z","time spent":"321.69309ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 "} {"level":"warn","ts":"2026-04-17T00:46:48.278762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.401698ms","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-17T00:46:48.278809Z","caller":"traceutil/trace.go:171","msg":"trace[131604056] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8225; }","duration":"363.458559ms","start":"2026-04-17T00:46:47.915339Z","end":"2026-04-17T00:46:48.278797Z","steps":["trace[131604056] 'agreement among raft nodes before linearized reading' (duration: 363.375377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.27888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.915295Z","time spent":"363.575382ms","remote":"127.0.0.1:40718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:46:48.278999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.332375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"warn","ts":"2026-04-17T00:46:48.279044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.376761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:46:48.27906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.004666ms","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":"warn","ts":"2026-04-17T00:46:48.279087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.440075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:46:48.278996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.268388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:48.279103Z","caller":"traceutil/trace.go:171","msg":"trace[1931179999] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8225; }","duration":"299.464172ms","start":"2026-04-17T00:46:47.979624Z","end":"2026-04-17T00:46:48.279089Z","steps":["trace[1931179999] 'agreement among raft nodes before linearized reading' (duration: 299.37278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.279149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.777378ms","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":"info","ts":"2026-04-17T00:46:48.279154Z","caller":"traceutil/trace.go:171","msg":"trace[1639417724] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8225; }","duration":"284.487506ms","start":"2026-04-17T00:46:47.994639Z","end":"2026-04-17T00:46:48.279126Z","steps":["trace[1639417724] 'agreement among raft nodes before linearized reading' (duration: 284.414134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.279152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.480021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:48.279183Z","caller":"traceutil/trace.go:171","msg":"trace[700225081] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8225; }","duration":"363.820829ms","start":"2026-04-17T00:46:47.915355Z","end":"2026-04-17T00:46:48.279176Z","steps":["trace[700225081] 'agreement among raft nodes before linearized reading' (duration: 363.772398ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:48.279204Z","caller":"traceutil/trace.go:171","msg":"trace[262909962] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8225; }","duration":"340.546072ms","start":"2026-04-17T00:46:47.938642Z","end":"2026-04-17T00:46:48.279188Z","steps":["trace[262909962] 'agreement among raft nodes before linearized reading' (duration: 340.47758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.279244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.676119ms","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":"info","ts":"2026-04-17T00:46:48.279311Z","caller":"traceutil/trace.go:171","msg":"trace[1332691200] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8225; }","duration":"379.758251ms","start":"2026-04-17T00:46:47.899534Z","end":"2026-04-17T00:46:48.279292Z","steps":["trace[1332691200] 'agreement among raft nodes before linearized reading' (duration: 379.643918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.279384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.839214ms","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":"warn","ts":"2026-04-17T00:46:48.279396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.212616ms","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":"info","ts":"2026-04-17T00:46:48.279421Z","caller":"traceutil/trace.go:171","msg":"trace[1218754963] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8225; }","duration":"379.953746ms","start":"2026-04-17T00:46:47.899458Z","end":"2026-04-17T00:46:48.279412Z","steps":["trace[1218754963] 'agreement among raft nodes before linearized reading' (duration: 379.843953ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:48.27915Z","caller":"traceutil/trace.go:171","msg":"trace[144667364] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8225; }","duration":"299.465183ms","start":"2026-04-17T00:46:47.979667Z","end":"2026-04-17T00:46:48.279132Z","steps":["trace[144667364] 'agreement among raft nodes before linearized reading' (duration: 299.200575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.279462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.899445Z","time spent":"380.006458ms","remote":"127.0.0.1:40708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 "} {"level":"info","ts":"2026-04-17T00:46:48.279089Z","caller":"traceutil/trace.go:171","msg":"trace[1458420473] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8225; }","duration":"140.485069ms","start":"2026-04-17T00:46:48.138582Z","end":"2026-04-17T00:46:48.279068Z","steps":["trace[1458420473] 'agreement among raft nodes before linearized reading' (duration: 140.225752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.279422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.143533ms","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-17T00:46:48.279559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.88215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T00:46:48.279214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.915349Z","time spent":"363.856961ms","remote":"127.0.0.1:40718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 "} {"level":"info","ts":"2026-04-17T00:46:48.279633Z","caller":"traceutil/trace.go:171","msg":"trace[1063074241] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8225; }","duration":"283.978573ms","start":"2026-04-17T00:46:47.995628Z","end":"2026-04-17T00:46:48.279606Z","steps":["trace[1063074241] 'agreement among raft nodes before linearized reading' (duration: 283.906311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.278994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.148037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:48.2797Z","caller":"traceutil/trace.go:171","msg":"trace[714011722] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8225; }","duration":"284.922139ms","start":"2026-04-17T00:46:47.99476Z","end":"2026-04-17T00:46:48.279683Z","steps":["trace[714011722] 'agreement among raft nodes before linearized reading' (duration: 284.087716ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:48.279568Z","caller":"traceutil/trace.go:171","msg":"trace[990532660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8225; }","duration":"219.323478ms","start":"2026-04-17T00:46:48.060226Z","end":"2026-04-17T00:46:48.27955Z","steps":["trace[990532660] 'agreement among raft nodes before linearized reading' (duration: 219.056471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.279255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.118754ms","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-17T00:46:48.279823Z","caller":"traceutil/trace.go:171","msg":"trace[163127106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8225; }","duration":"205.71703ms","start":"2026-04-17T00:46:48.074095Z","end":"2026-04-17T00:46:48.279812Z","steps":["trace[163127106] 'agreement among raft nodes before linearized reading' (duration: 204.99003ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:48.27946Z","caller":"traceutil/trace.go:171","msg":"trace[841807465] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8225; }","duration":"321.35271ms","start":"2026-04-17T00:46:47.958094Z","end":"2026-04-17T00:46:48.279446Z","steps":["trace[841807465] 'agreement among raft nodes before linearized reading' (duration: 321.213696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.279916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.958065Z","time spent":"321.830073ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 "} {"level":"warn","ts":"2026-04-17T00:46:48.279084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.220606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:48.280044Z","caller":"traceutil/trace.go:171","msg":"trace[1080931053] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8225; }","duration":"340.192454ms","start":"2026-04-17T00:46:47.939836Z","end":"2026-04-17T00:46:48.280029Z","steps":["trace[1080931053] 'agreement among raft nodes before linearized reading' (duration: 339.221307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.279255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.938637Z","time spent":"340.607035ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:46:48.280092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.939827Z","time spent":"340.250586ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 "} {"level":"warn","ts":"2026-04-17T00:46:48.279416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.899527Z","time spent":"379.871354ms","remote":"127.0.0.1:40708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-17T00:46:48.279469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.278858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:48.2803Z","caller":"traceutil/trace.go:171","msg":"trace[169734290] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8225; }","duration":"300.131642ms","start":"2026-04-17T00:46:47.980156Z","end":"2026-04-17T00:46:48.280288Z","steps":["trace[169734290] 'agreement among raft nodes before linearized reading' (duration: 299.279988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.280344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.98015Z","time spent":"300.181904ms","remote":"127.0.0.1:40738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 "} {"level":"warn","ts":"2026-04-17T00:46:48.279512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.767423ms","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-17T00:46:48.28041Z","caller":"traceutil/trace.go:171","msg":"trace[440394308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8225; }","duration":"259.654658ms","start":"2026-04-17T00:46:48.020738Z","end":"2026-04-17T00:46:48.280392Z","steps":["trace[440394308] 'agreement among raft nodes before linearized reading' (duration: 258.714981ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:46:48.279103Z","caller":"traceutil/trace.go:171","msg":"trace[1218852350] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8225; }","duration":"363.044017ms","start":"2026-04-17T00:46:47.916045Z","end":"2026-04-17T00:46:48.279089Z","steps":["trace[1218852350] 'agreement among raft nodes before linearized reading' (duration: 362.989366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.280537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.916032Z","time spent":"364.493728ms","remote":"127.0.0.1:40718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 "} {"level":"warn","ts":"2026-04-17T00:46:48.278995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.328916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:46:48.280718Z","caller":"traceutil/trace.go:171","msg":"trace[1966001005] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8225; }","duration":"342.108807ms","start":"2026-04-17T00:46:47.938587Z","end":"2026-04-17T00:46:48.280696Z","steps":["trace[1966001005] 'agreement among raft nodes before linearized reading' (duration: 340.283885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.280765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.728807ms","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-17T00:46:48.280798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.938571Z","time spent":"342.202819ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 "} {"level":"info","ts":"2026-04-17T00:46:48.280793Z","caller":"traceutil/trace.go:171","msg":"trace[1644343713] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8225; }","duration":"323.793709ms","start":"2026-04-17T00:46:47.95699Z","end":"2026-04-17T00:46:48.280784Z","steps":["trace[1644343713] 'agreement among raft nodes before linearized reading' (duration: 321.571407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:46:48.281034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:46:47.95697Z","time spent":"324.044385ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"info","ts":"2026-04-17T00:47:16.322607Z","caller":"traceutil/trace.go:171","msg":"trace[434681858] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"286.252256ms","start":"2026-04-17T00:47:16.036314Z","end":"2026-04-17T00:47:16.322566Z","steps":["trace[434681858] 'process raft request' (duration: 286.04319ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:47:16.579536Z","caller":"traceutil/trace.go:171","msg":"trace[1032344266] linearizableReadLoop","detail":"{readStateIndex:8845; appliedIndex:8842; }","duration":"136.21082ms","start":"2026-04-17T00:47:16.443299Z","end":"2026-04-17T00:47:16.57951Z","steps":["trace[1032344266] 'read index received' (duration: 40.395511ms)","trace[1032344266] 'applied index is now lower than readState.Index' (duration: 95.814689ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:47:16.579742Z","caller":"traceutil/trace.go:171","msg":"trace[527832297] transaction","detail":"{read_only:false; response_revision:8464; number_of_response:1; }","duration":"252.107748ms","start":"2026-04-17T00:47:16.327626Z","end":"2026-04-17T00:47:16.579734Z","steps":["trace[527832297] 'process raft request' (duration: 156.084162ms)","trace[527832297] 'compare' (duration: 95.643145ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:47:16.579865Z","caller":"traceutil/trace.go:171","msg":"trace[87940185] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"251.885032ms","start":"2026-04-17T00:47:16.327973Z","end":"2026-04-17T00:47:16.579858Z","steps":["trace[87940185] 'process raft request' (duration: 251.48735ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:47:16.579935Z","caller":"traceutil/trace.go:171","msg":"trace[54046350] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"250.956237ms","start":"2026-04-17T00:47:16.328972Z","end":"2026-04-17T00:47:16.579928Z","steps":["trace[54046350] 'process raft request' (duration: 250.517274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:47:16.580063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.750466ms","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-17T00:47:16.580085Z","caller":"traceutil/trace.go:171","msg":"trace[127938456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8466; }","duration":"136.834558ms","start":"2026-04-17T00:47:16.443244Z","end":"2026-04-17T00:47:16.580079Z","steps":["trace[127938456] 'agreement among raft nodes before linearized reading' (duration: 136.741925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:47:16.580166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.743922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T00:47:16.580181Z","caller":"traceutil/trace.go:171","msg":"trace[2107865929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8466; }","duration":"111.798784ms","start":"2026-04-17T00:47:16.468379Z","end":"2026-04-17T00:47:16.580177Z","steps":["trace[2107865929] 'agreement among raft nodes before linearized reading' (duration: 111.771703ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:47:30.013633Z","caller":"traceutil/trace.go:171","msg":"trace[2027776719] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"281.656808ms","start":"2026-04-17T00:47:29.731943Z","end":"2026-04-17T00:47:30.0136Z","steps":["trace[2027776719] 'process raft request' (duration: 281.469563ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:47:30.066383Z","caller":"traceutil/trace.go:171","msg":"trace[1589783663] linearizableReadLoop","detail":"{readStateIndex:8987; appliedIndex:8985; }","duration":"177.608081ms","start":"2026-04-17T00:47:29.888748Z","end":"2026-04-17T00:47:30.066356Z","steps":["trace[1589783663] 'read index received' (duration: 124.639001ms)","trace[1589783663] 'applied index is now lower than readState.Index' (duration: 52.96775ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:47:30.066467Z","caller":"traceutil/trace.go:171","msg":"trace[486084936] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"328.594762ms","start":"2026-04-17T00:47:29.737845Z","end":"2026-04-17T00:47:30.06644Z","steps":["trace[486084936] 'process raft request' (duration: 322.731099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:47:30.066544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.769205ms","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":"warn","ts":"2026-04-17T00:47:30.066604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T00:47:29.737823Z","time spent":"328.702824ms","remote":"127.0.0.1:35352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4599,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T00:47:30.066619Z","caller":"traceutil/trace.go:171","msg":"trace[247950486] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:8600; }","duration":"177.912939ms","start":"2026-04-17T00:47:29.888686Z","end":"2026-04-17T00:47:30.066599Z","steps":["trace[247950486] 'agreement among raft nodes before linearized reading' (duration: 177.773155ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:47:30.914608Z","caller":"traceutil/trace.go:171","msg":"trace[360282056] linearizableReadLoop","detail":"{readStateIndex:8992; appliedIndex:8990; }","duration":"181.355535ms","start":"2026-04-17T00:47:30.733217Z","end":"2026-04-17T00:47:30.914573Z","steps":["trace[360282056] 'read index received' (duration: 123.136079ms)","trace[360282056] 'applied index is now lower than readState.Index' (duration: 58.217826ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:47:30.914782Z","caller":"traceutil/trace.go:171","msg":"trace[358473230] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"256.745047ms","start":"2026-04-17T00:47:30.658022Z","end":"2026-04-17T00:47:30.914767Z","steps":["trace[358473230] 'process raft request' (duration: 198.315215ms)","trace[358473230] 'compare' (duration: 57.954969ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:47:30.91497Z","caller":"traceutil/trace.go:171","msg":"trace[2133872586] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"256.370557ms","start":"2026-04-17T00:47:30.658535Z","end":"2026-04-17T00:47:30.914905Z","steps":["trace[2133872586] 'process raft request' (duration: 255.952486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:47:30.915157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.676611ms","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-17T00:47:30.915222Z","caller":"traceutil/trace.go:171","msg":"trace[1439618052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8605; }","duration":"258.768903ms","start":"2026-04-17T00:47:30.656437Z","end":"2026-04-17T00:47:30.915206Z","steps":["trace[1439618052] 'agreement among raft nodes before linearized reading' (duration: 258.464535ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:47:30.94153Z","caller":"traceutil/trace.go:171","msg":"trace[12635973] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"202.717637ms","start":"2026-04-17T00:47:30.73876Z","end":"2026-04-17T00:47:30.941477Z","steps":["trace[12635973] 'process raft request' (duration: 202.246284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:47:30.941721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.893173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-17T00:47:30.941844Z","caller":"traceutil/trace.go:171","msg":"trace[683413142] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8606; }","duration":"158.064167ms","start":"2026-04-17T00:47:30.783751Z","end":"2026-04-17T00:47:30.941815Z","steps":["trace[683413142] 'agreement among raft nodes before linearized reading' (duration: 157.702237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T00:47:30.941743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.463764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rnb5t\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-17T00:47:30.94193Z","caller":"traceutil/trace.go:171","msg":"trace[1841286728] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rnb5t; range_end:; response_count:1; response_revision:8606; }","duration":"151.719551ms","start":"2026-04-17T00:47:30.790195Z","end":"2026-04-17T00:47:30.941914Z","steps":["trace[1841286728] 'agreement among raft nodes before linearized reading' (duration: 151.33326ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T00:48:38.82293Z","caller":"traceutil/trace.go:171","msg":"trace[674302597] transaction","detail":"{read_only:false; response_revision:9334; number_of_response:1; }","duration":"105.059986ms","start":"2026-04-17T00:48:38.717845Z","end":"2026-04-17T00:48:38.822905Z","steps":["trace[674302597] 'process raft request' (duration: 83.532949ms)","trace[674302597] 'compare' (duration: 21.338042ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T00:49:05.582577Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6679} {"level":"info","ts":"2026-04-17T00:49:05.675363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6679,"took":"90.957434ms","hash":4021648665,"current-db-size-bytes":60215296,"current-db-size":"60 MB","current-db-size-in-use-bytes":26972160,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-17T00:49:05.675453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4021648665,"revision":6679,"compact-revision":3315} {"level":"info","ts":"2026-04-17T00:49:06.009718Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T00:49:06.017574Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-17T00:49:06.017699Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-17T00:54:05.592162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9567} {"level":"info","ts":"2026-04-17T00:54:05.696198Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9567,"took":"101.633932ms","hash":3054318721,"current-db-size-bytes":60215296,"current-db-size":"60 MB","current-db-size-in-use-bytes":25333760,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-17T00:54:05.696271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3054318721,"revision":9567,"compact-revision":6679}