{"level":"warn","ts":"2026-07-22T22:05:39.526873Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:05:39.526949Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-22T22:05:39.527012Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:05:39.527023Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-07-22T22:05:39.527041Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-22T22:05:39.527633Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-07-22T22:05:39.527719Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-22T22:05:39.53145Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.51141ms"} {"level":"info","ts":"2026-07-22T22:05:39.543954Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-07-22T22:05:39.544062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T22:05:39.544101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-07-22T22:05:39.544122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T22:05:39.544144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-07-22T22:05:39.544203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-07-22T22:05:39.553861Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T22:05:39.555364Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T22:05:39.556662Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-22T22:05:39.559486Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T22:05:39.559693Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T22:05:39.559773Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T22:05:39.559867Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T22:05:39.559884Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T22:05:39.562768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-07-22T22:05:39.56312Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-07-22T22:05:39.56702Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-22T22:05:39.567239Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T22:05:39.567248Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-07-22T22:05:39.567306Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-07-22T22:05:39.567298Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T22:05:39.746627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T22:05:39.746695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T22:05:39.746719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-07-22T22:05:39.746736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-07-22T22:05:39.746744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-07-22T22:05:39.746754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-07-22T22:05:39.746761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-07-22T22:05:39.749615Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:05:39.750566Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:05:39.75057Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T22:05:39.750722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:05:39.750963Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T22:05:39.751001Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T22:05:39.753164Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-07-22T22:05:39.753402Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:05:39.753498Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:05:39.753526Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:05:39.753806Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T22:05:56.758224Z","caller":"traceutil/trace.go:171","msg":"trace[549990674] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"125.276925ms","start":"2026-07-22T22:05:56.632922Z","end":"2026-07-22T22:05:56.758199Z","steps":["trace[549990674] 'process raft request' (duration: 125.131981ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:06:21.837911Z","caller":"traceutil/trace.go:171","msg":"trace[250683844] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"133.605262ms","start":"2026-07-22T22:06:21.704267Z","end":"2026-07-22T22:06:21.837872Z","steps":["trace[250683844] 'process raft request' (duration: 75.656713ms)","trace[250683844] 'compare' (duration: 57.769046ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:06:22.360584Z","caller":"traceutil/trace.go:171","msg":"trace[848990150] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"344.778743ms","start":"2026-07-22T22:06:22.015786Z","end":"2026-07-22T22:06:22.360565Z","steps":["trace[848990150] 'read index received' (duration: 337.382594ms)","trace[848990150] 'applied index is now lower than readState.Index' (duration: 7.395079ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:06:22.360751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.959357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vvj9j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-22T22:06:22.360805Z","caller":"traceutil/trace.go:171","msg":"trace[1141169889] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vvj9j; range_end:; response_count:1; response_revision:630; }","duration":"345.068129ms","start":"2026-07-22T22:06:22.015728Z","end":"2026-07-22T22:06:22.360796Z","steps":["trace[1141169889] 'agreement among raft nodes before linearized reading' (duration: 344.910026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:22.360835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:22.015704Z","time spent":"345.12463ms","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-vvj9j\" "} {"level":"info","ts":"2026-07-22T22:06:22.360927Z","caller":"traceutil/trace.go:171","msg":"trace[1779867161] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"441.650921ms","start":"2026-07-22T22:06:21.919229Z","end":"2026-07-22T22:06:22.36088Z","steps":["trace[1779867161] 'process raft request' (duration: 433.863245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:22.361718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:21.919207Z","time spent":"441.824774ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:06:22.36511Z","caller":"traceutil/trace.go:171","msg":"trace[258866699] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"133.636231ms","start":"2026-07-22T22:06:22.231453Z","end":"2026-07-22T22:06:22.36509Z","steps":["trace[258866699] 'process raft request' (duration: 133.223313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:37.830515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.782074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T22:06:37.830636Z","caller":"traceutil/trace.go:171","msg":"trace[972439552] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"198.974288ms","start":"2026-07-22T22:06:37.631642Z","end":"2026-07-22T22:06:37.830617Z","steps":["trace[972439552] 'range keys from in-memory index tree' (duration: 198.602211ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:07:46.865261Z","caller":"traceutil/trace.go:171","msg":"trace[811200656] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"273.128941ms","start":"2026-07-22T22:07:46.592083Z","end":"2026-07-22T22:07:46.865212Z","steps":["trace[811200656] 'process raft request' (duration: 272.650071ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:07:47.159613Z","caller":"traceutil/trace.go:171","msg":"trace[2012379696] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"109.717332ms","start":"2026-07-22T22:07:47.049872Z","end":"2026-07-22T22:07:47.159589Z","steps":["trace[2012379696] 'process raft request' (duration: 109.549169ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:07:59.541139Z","caller":"traceutil/trace.go:171","msg":"trace[397429173] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"109.206872ms","start":"2026-07-22T22:07:59.431898Z","end":"2026-07-22T22:07:59.541105Z","steps":["trace[397429173] 'process raft request' (duration: 108.898145ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:08:31.930865Z","caller":"traceutil/trace.go:171","msg":"trace[1752802635] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"235.82199ms","start":"2026-07-22T22:08:31.695007Z","end":"2026-07-22T22:08:31.930829Z","steps":["trace[1752802635] 'process raft request' (duration: 235.622726ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:08:31.931619Z","caller":"traceutil/trace.go:171","msg":"trace[1952503121] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2209; }","duration":"222.054369ms","start":"2026-07-22T22:08:31.709541Z","end":"2026-07-22T22:08:31.931595Z","steps":["trace[1952503121] 'read index received' (duration: 221.13539ms)","trace[1952503121] 'applied index is now lower than readState.Index' (duration: 917.119µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:08:31.931655Z","caller":"traceutil/trace.go:171","msg":"trace[1229565142] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"222.630371ms","start":"2026-07-22T22:08:31.708995Z","end":"2026-07-22T22:08:31.931626Z","steps":["trace[1229565142] 'process raft request' (duration: 222.490718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:31.93189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.310309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-07-22T22:08:31.931882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.908319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:08:31.93195Z","caller":"traceutil/trace.go:171","msg":"trace[1421759680] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2129; }","duration":"164.440291ms","start":"2026-07-22T22:08:31.767493Z","end":"2026-07-22T22:08:31.931934Z","steps":["trace[1421759680] 'agreement among raft nodes before linearized reading' (duration: 164.234897ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:08:31.931965Z","caller":"traceutil/trace.go:171","msg":"trace[3422078] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2129; }","duration":"138.034282ms","start":"2026-07-22T22:08:31.793911Z","end":"2026-07-22T22:08:31.931945Z","steps":["trace[3422078] 'agreement among raft nodes before linearized reading' (duration: 137.838308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:08:31.93189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.336505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T22:08:31.932034Z","caller":"traceutil/trace.go:171","msg":"trace[1165992046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2129; }","duration":"222.535759ms","start":"2026-07-22T22:08:31.709484Z","end":"2026-07-22T22:08:31.93202Z","steps":["trace[1165992046] 'agreement among raft nodes before linearized reading' (duration: 222.233003ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:54.604313Z","caller":"traceutil/trace.go:171","msg":"trace[349610165] transaction","detail":"{read_only:false; response_revision:2625; number_of_response:1; }","duration":"229.428673ms","start":"2026-07-22T22:09:54.374861Z","end":"2026-07-22T22:09:54.60429Z","steps":["trace[349610165] 'process raft request' (duration: 214.475768ms)","trace[349610165] 'compare' (duration: 14.585307ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:54.619743Z","caller":"traceutil/trace.go:171","msg":"trace[1936826086] linearizableReadLoop","detail":"{readStateIndex:2727; appliedIndex:2725; }","duration":"174.264897ms","start":"2026-07-22T22:09:54.445456Z","end":"2026-07-22T22:09:54.619721Z","steps":["trace[1936826086] 'read index received' (duration: 143.900777ms)","trace[1936826086] 'applied index is now lower than readState.Index' (duration: 30.36291ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:09:54.619887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.424821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:09:54.619914Z","caller":"traceutil/trace.go:171","msg":"trace[277723768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2626; }","duration":"174.514382ms","start":"2026-07-22T22:09:54.445393Z","end":"2026-07-22T22:09:54.619907Z","steps":["trace[277723768] 'agreement among raft nodes before linearized reading' (duration: 174.4278ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:54.619888Z","caller":"traceutil/trace.go:171","msg":"trace[1841977952] transaction","detail":"{read_only:false; response_revision:2626; number_of_response:1; }","duration":"244.753686ms","start":"2026-07-22T22:09:54.37502Z","end":"2026-07-22T22:09:54.619773Z","steps":["trace[1841977952] 'process raft request' (duration: 244.574583ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:41.66522Z","caller":"traceutil/trace.go:171","msg":"trace[482581862] transaction","detail":"{read_only:false; response_revision:3695; number_of_response:1; }","duration":"138.203837ms","start":"2026-07-22T22:12:41.526998Z","end":"2026-07-22T22:12:41.665202Z","steps":["trace[482581862] 'process raft request' (duration: 137.977232ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:41.6652Z","caller":"traceutil/trace.go:171","msg":"trace[2085093157] linearizableReadLoop","detail":"{readStateIndex:3836; appliedIndex:3835; }","duration":"130.5738ms","start":"2026-07-22T22:12:41.534599Z","end":"2026-07-22T22:12:41.665173Z","steps":["trace[2085093157] 'read index received' (duration: 130.387536ms)","trace[2085093157] 'applied index is now lower than readState.Index' (duration: 185.324µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:12:41.665578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.949738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-07-22T22:12:41.665632Z","caller":"traceutil/trace.go:171","msg":"trace[276021672] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3695; }","duration":"131.08293ms","start":"2026-07-22T22:12:41.534534Z","end":"2026-07-22T22:12:41.665617Z","steps":["trace[276021672] 'agreement among raft nodes before linearized reading' (duration: 130.719613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:41.689135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.614962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:12:41.689202Z","caller":"traceutil/trace.go:171","msg":"trace[168750689] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3696; }","duration":"112.725745ms","start":"2026-07-22T22:12:41.576462Z","end":"2026-07-22T22:12:41.689188Z","steps":["trace[168750689] 'agreement among raft nodes before linearized reading' (duration: 112.622363ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:13:55.222097Z","caller":"traceutil/trace.go:171","msg":"trace[551430776] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"125.731141ms","start":"2026-07-22T22:13:55.096336Z","end":"2026-07-22T22:13:55.222067Z","steps":["trace[551430776] 'process raft request' (duration: 125.560088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:57.436347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.612827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:13:57.436419Z","caller":"traceutil/trace.go:171","msg":"trace[387499246] linearizableReadLoop","detail":"{readStateIndex:5009; appliedIndex:5008; }","duration":"128.998878ms","start":"2026-07-22T22:13:57.307409Z","end":"2026-07-22T22:13:57.436408Z","steps":["trace[387499246] 'read index received' (duration: 6.134095ms)","trace[387499246] 'applied index is now lower than readState.Index' (duration: 122.863913ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:13:57.436484Z","caller":"traceutil/trace.go:171","msg":"trace[1091110202] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"203.755916ms","start":"2026-07-22T22:13:57.232692Z","end":"2026-07-22T22:13:57.436448Z","steps":["trace[1091110202] 'process raft request' (duration: 80.891034ms)","trace[1091110202] 'compare' (duration: 122.443853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:13:57.436548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.589657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-2ncj6.18c4bc5740d1f9c1\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-07-22T22:13:57.436568Z","caller":"traceutil/trace.go:171","msg":"trace[1135005402] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-2ncj6.18c4bc5740d1f9c1; range_end:; response_count:1; response_revision:4824; }","duration":"123.637288ms","start":"2026-07-22T22:13:57.312923Z","end":"2026-07-22T22:13:57.43656Z","steps":["trace[1135005402] 'agreement among raft nodes before linearized reading' (duration: 123.554226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:57.43673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.296193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f13b2161-1d9f-4d04-ba91-912063ea19a0\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-22T22:13:57.436807Z","caller":"traceutil/trace.go:171","msg":"trace[1551149351] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f13b2161-1d9f-4d04-ba91-912063ea19a0; range_end:; response_count:1; response_revision:4824; }","duration":"129.413466ms","start":"2026-07-22T22:13:57.307373Z","end":"2026-07-22T22:13:57.436786Z","steps":["trace[1551149351] 'agreement among raft nodes before linearized reading' (duration: 129.10299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:14:18.475311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.29854ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-22T22:14:18.475402Z","caller":"traceutil/trace.go:171","msg":"trace[521447393] linearizableReadLoop","detail":"{readStateIndex:5445; appliedIndex:5444; }","duration":"196.64539ms","start":"2026-07-22T22:14:18.27874Z","end":"2026-07-22T22:14:18.475385Z","steps":["trace[521447393] 'read index received' (duration: 35.661µs)","trace[521447393] 'applied index is now lower than readState.Index' (duration: 196.608319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:14:18.475555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.820565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T22:14:18.475583Z","caller":"traceutil/trace.go:171","msg":"trace[1160126308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5246; }","duration":"196.891165ms","start":"2026-07-22T22:14:18.278684Z","end":"2026-07-22T22:14:18.475575Z","steps":["trace[1160126308] 'agreement among raft nodes before linearized reading' (duration: 196.773233ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:14:18.787867Z","caller":"traceutil/trace.go:171","msg":"trace[1810826080] linearizableReadLoop","detail":"{readStateIndex:5447; appliedIndex:5446; }","duration":"157.945749ms","start":"2026-07-22T22:14:18.629896Z","end":"2026-07-22T22:14:18.787842Z","steps":["trace[1810826080] 'read index received' (duration: 157.685514ms)","trace[1810826080] 'applied index is now lower than readState.Index' (duration: 259.225µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:14:18.78817Z","caller":"traceutil/trace.go:171","msg":"trace[1777853944] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"214.014717ms","start":"2026-07-22T22:14:18.574098Z","end":"2026-07-22T22:14:18.788113Z","steps":["trace[1777853944] 'process raft request' (duration: 213.543806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:14:18.788234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.270817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:14:18.788396Z","caller":"traceutil/trace.go:171","msg":"trace[181662349] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5248; }","duration":"158.47006ms","start":"2026-07-22T22:14:18.62987Z","end":"2026-07-22T22:14:18.78834Z","steps":["trace[181662349] 'agreement among raft nodes before linearized reading' (duration: 158.206644ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:15:40.016221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2864} {"level":"info","ts":"2026-07-22T22:15:40.085547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2864,"took":"67.976994ms","hash":1133648916,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":33660928,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-22T22:15:40.085624Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1133648916,"revision":2864,"compact-revision":-1} {"level":"info","ts":"2026-07-22T22:20:00.515217Z","caller":"traceutil/trace.go:171","msg":"trace[1378875812] transaction","detail":"{read_only:false; response_revision:7961; number_of_response:1; }","duration":"191.408273ms","start":"2026-07-22T22:20:00.323784Z","end":"2026-07-22T22:20:00.515192Z","steps":["trace[1378875812] 'process raft request' (duration: 191.063726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:20:13.029258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.455554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-22T22:20:13.029369Z","caller":"traceutil/trace.go:171","msg":"trace[89434084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8024; }","duration":"214.654079ms","start":"2026-07-22T22:20:12.814689Z","end":"2026-07-22T22:20:13.029343Z","steps":["trace[89434084] 'range keys from in-memory index tree' (duration: 214.24736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:20:13.029435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.985342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-22T22:20:13.029524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.196204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4thcq\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-22T22:20:13.029608Z","caller":"traceutil/trace.go:171","msg":"trace[1930478659] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4thcq; range_end:; response_count:1; response_revision:8024; }","duration":"262.304416ms","start":"2026-07-22T22:20:12.767273Z","end":"2026-07-22T22:20:13.029577Z","steps":["trace[1930478659] 'range keys from in-memory index tree' (duration: 262.03485ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:20:13.029615Z","caller":"traceutil/trace.go:171","msg":"trace[707868857] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8024; }","duration":"287.117924ms","start":"2026-07-22T22:20:12.742405Z","end":"2026-07-22T22:20:13.029523Z","steps":["trace[707868857] 'count revisions from in-memory index tree' (duration: 286.871259ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:20:13.07687Z","caller":"traceutil/trace.go:171","msg":"trace[807878308] transaction","detail":"{read_only:false; response_revision:8025; number_of_response:1; }","duration":"167.730495ms","start":"2026-07-22T22:20:12.909117Z","end":"2026-07-22T22:20:13.076847Z","steps":["trace[807878308] 'process raft request' (duration: 167.560842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:20:40.23393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.146909ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:5"} {"level":"info","ts":"2026-07-22T22:20:40.234157Z","caller":"traceutil/trace.go:171","msg":"trace[507982181] compact","detail":"{revision:6002; response_revision:8223; }","duration":"181.151623ms","start":"2026-07-22T22:20:40.05299Z","end":"2026-07-22T22:20:40.234142Z","steps":["trace[507982181] 'check and update compact revision' (duration: 155.051707ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:20:40.235071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6002} {"level":"info","ts":"2026-07-22T22:20:40.235567Z","caller":"traceutil/trace.go:171","msg":"trace[212263327] transaction","detail":"{read_only:false; response_revision:8224; number_of_response:1; }","duration":"179.718384ms","start":"2026-07-22T22:20:40.055824Z","end":"2026-07-22T22:20:40.235543Z","steps":["trace[212263327] 'process raft request' (duration: 178.220403ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:20:40.23556Z","caller":"traceutil/trace.go:171","msg":"trace[718811236] linearizableReadLoop","detail":"{readStateIndex:8517; appliedIndex:8515; }","duration":"147.582484ms","start":"2026-07-22T22:20:40.087946Z","end":"2026-07-22T22:20:40.235528Z","steps":["trace[718811236] 'read index received' (duration: 143.625384ms)","trace[718811236] 'applied index is now lower than readState.Index' (duration: 3.95508ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:20:40.237453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.493384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-07-22T22:20:40.237488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.243195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-22T22:20:40.237519Z","caller":"traceutil/trace.go:171","msg":"trace[1635306381] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8224; }","duration":"149.600145ms","start":"2026-07-22T22:20:40.087903Z","end":"2026-07-22T22:20:40.237503Z","steps":["trace[1635306381] 'agreement among raft nodes before linearized reading' (duration: 147.752147ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:20:40.237554Z","caller":"traceutil/trace.go:171","msg":"trace[2060950276] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8224; }","duration":"141.333657ms","start":"2026-07-22T22:20:40.096205Z","end":"2026-07-22T22:20:40.237538Z","steps":["trace[2060950276] 'agreement among raft nodes before linearized reading' (duration: 139.515779ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:20:40.330114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6002,"took":"93.102848ms","hash":1953384386,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":22859776,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-22T22:20:40.330184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1953384386,"revision":6002,"compact-revision":2864} {"level":"warn","ts":"2026-07-22T22:20:52.988062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.3685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-22T22:20:52.988156Z","caller":"traceutil/trace.go:171","msg":"trace[511911623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8331; }","duration":"165.566795ms","start":"2026-07-22T22:20:52.822566Z","end":"2026-07-22T22:20:52.988133Z","steps":["trace[511911623] 'range keys from in-memory index tree' (duration: 165.184414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:20:52.98852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.055854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-22T22:20:52.9886Z","caller":"traceutil/trace.go:171","msg":"trace[379111040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8331; }","duration":"167.189657ms","start":"2026-07-22T22:20:52.821392Z","end":"2026-07-22T22:20:52.988582Z","steps":["trace[379111040] 'range keys from in-memory index tree' (duration: 166.86061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:20:54.0326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.666803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-07-22T22:20:54.03295Z","caller":"traceutil/trace.go:171","msg":"trace[2023078271] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8340; }","duration":"122.063002ms","start":"2026-07-22T22:20:53.910866Z","end":"2026-07-22T22:20:54.032929Z","steps":["trace[2023078271] 'range keys from in-memory index tree' (duration: 121.49634ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:21:45.456762Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ed.wal"} {"level":"info","ts":"2026-07-22T22:23:24.140006Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-22T22:23:24.144871Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-22T22:23:24.144949Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-22T22:25:38.83711Z","caller":"traceutil/trace.go:171","msg":"trace[241070200] transaction","detail":"{read_only:false; response_revision:10888; number_of_response:1; }","duration":"353.499487ms","start":"2026-07-22T22:25:38.483583Z","end":"2026-07-22T22:25:38.837082Z","steps":["trace[241070200] 'process raft request' (duration: 353.337334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:25:38.837287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:25:38.483558Z","time spent":"353.646771ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:25:38.873815Z","caller":"traceutil/trace.go:171","msg":"trace[880032633] linearizableReadLoop","detail":"{readStateIndex:11261; appliedIndex:11258; }","duration":"118.80057ms","start":"2026-07-22T22:25:38.754989Z","end":"2026-07-22T22:25:38.873789Z","steps":["trace[880032633] 'read index received' (duration: 81.844164ms)","trace[880032633] 'applied index is now lower than readState.Index' (duration: 36.955326ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:25:38.873865Z","caller":"traceutil/trace.go:171","msg":"trace[1157274376] transaction","detail":"{read_only:false; response_revision:10889; number_of_response:1; }","duration":"362.340599ms","start":"2026-07-22T22:25:38.511487Z","end":"2026-07-22T22:25:38.873828Z","steps":["trace[1157274376] 'process raft request' (duration: 362.116014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:25:38.874031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:25:38.511462Z","time spent":"362.484372ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:25:38.874062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.054345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-22T22:25:38.874186Z","caller":"traceutil/trace.go:171","msg":"trace[336962573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10889; }","duration":"120.264719ms","start":"2026-07-22T22:25:38.75387Z","end":"2026-07-22T22:25:38.874135Z","steps":["trace[336962573] 'agreement among raft nodes before linearized reading' (duration: 120.018314ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:25:40.240185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8223} {"level":"info","ts":"2026-07-22T22:25:40.316242Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8223,"took":"73.80544ms","hash":258725488,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":26779648,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-22T22:25:40.316319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":258725488,"revision":8223,"compact-revision":6002} {"level":"info","ts":"2026-07-22T22:30:13.973283Z","caller":"traceutil/trace.go:171","msg":"trace[1765924237] transaction","detail":"{read_only:false; response_revision:12762; number_of_response:1; }","duration":"135.149387ms","start":"2026-07-22T22:30:13.838108Z","end":"2026-07-22T22:30:13.973258Z","steps":["trace[1765924237] 'process raft request' (duration: 135.018764ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:30:40.245832Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10896} {"level":"info","ts":"2026-07-22T22:30:40.326205Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10896,"took":"77.802662ms","hash":1850406010,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":27860992,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-22T22:30:40.326261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1850406010,"revision":10896,"compact-revision":8223} {"level":"info","ts":"2026-07-22T22:31:58.348831Z","caller":"traceutil/trace.go:171","msg":"trace[1798705803] transaction","detail":"{read_only:false; response_revision:13454; number_of_response:1; }","duration":"132.883438ms","start":"2026-07-22T22:31:58.215916Z","end":"2026-07-22T22:31:58.348799Z","steps":["trace[1798705803] 'process raft request' (duration: 120.514615ms)","trace[1798705803] 'compare' (duration: 12.147569ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:31:59.309994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.156938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:31:59.310092Z","caller":"traceutil/trace.go:171","msg":"trace[109896744] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:13458; }","duration":"273.309392ms","start":"2026-07-22T22:31:59.036759Z","end":"2026-07-22T22:31:59.310069Z","steps":["trace[109896744] 'count revisions from in-memory index tree' (duration: 273.002496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:31:59.31024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.399616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:31:59.310485Z","caller":"traceutil/trace.go:171","msg":"trace[1140545730] transaction","detail":"{read_only:false; response_revision:13459; number_of_response:1; }","duration":"209.614768ms","start":"2026-07-22T22:31:59.100844Z","end":"2026-07-22T22:31:59.310459Z","steps":["trace[1140545730] 'process raft request' (duration: 38.895246ms)","trace[1140545730] 'compare' (duration: 170.239253ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:32:20.228823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.283247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:32:20.229376Z","caller":"traceutil/trace.go:171","msg":"trace[1334735941] transaction","detail":"{read_only:false; response_revision:13762; number_of_response:1; }","duration":"327.162093ms","start":"2026-07-22T22:32:19.902188Z","end":"2026-07-22T22:32:20.22935Z","steps":["trace[1334735941] 'process raft request' (duration: 63.258974ms)","trace[1334735941] 'compare' (duration: 263.119313ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:32:20.22947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:32:19.902166Z","time spent":"327.259746ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:32:20.229831Z","caller":"traceutil/trace.go:171","msg":"trace[408727980] linearizableReadLoop","detail":"{readStateIndex:14241; appliedIndex:14239; }","duration":"288.661916ms","start":"2026-07-22T22:32:19.941157Z","end":"2026-07-22T22:32:20.229819Z","steps":["trace[408727980] 'read index received' (duration: 24.263047ms)","trace[408727980] 'applied index is now lower than readState.Index' (duration: 264.397708ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:32:20.229922Z","caller":"traceutil/trace.go:171","msg":"trace[1679686698] transaction","detail":"{read_only:false; response_revision:13763; number_of_response:1; }","duration":"326.997639ms","start":"2026-07-22T22:32:19.902912Z","end":"2026-07-22T22:32:20.22991Z","steps":["trace[1679686698] 'process raft request' (duration: 326.391077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:32:20.229996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:32:19.902881Z","time spent":"327.07682ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:32:20.230061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.88279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-22T22:32:20.23015Z","caller":"traceutil/trace.go:171","msg":"trace[1929382088] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13763; }","duration":"289.029973ms","start":"2026-07-22T22:32:19.9411Z","end":"2026-07-22T22:32:20.23013Z","steps":["trace[1929382088] 'agreement among raft nodes before linearized reading' (duration: 288.801918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:32:20.230456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.202677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-27fa2880-4ac6-4763-ab40-454dccc9531e\" ","response":"range_response_count:1 size:3637"} {"level":"warn","ts":"2026-07-22T22:32:20.230447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.845259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-27fa2880-4ac6-4763-ab40-454dccc9531e\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-07-22T22:32:20.230543Z","caller":"traceutil/trace.go:171","msg":"trace[915879177] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-27fa2880-4ac6-4763-ab40-454dccc9531e; range_end:; response_count:1; response_revision:13763; }","duration":"136.3267ms","start":"2026-07-22T22:32:20.094197Z","end":"2026-07-22T22:32:20.230524Z","steps":["trace[915879177] 'agreement among raft nodes before linearized reading' (duration: 136.086894ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:32:20.23057Z","caller":"traceutil/trace.go:171","msg":"trace[393541229] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-27fa2880-4ac6-4763-ab40-454dccc9531e; range_end:; response_count:1; response_revision:13763; }","duration":"285.985901ms","start":"2026-07-22T22:32:19.94457Z","end":"2026-07-22T22:32:20.230556Z","steps":["trace[393541229] 'agreement among raft nodes before linearized reading' (duration: 285.679244ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:33:44.96748Z","caller":"traceutil/trace.go:171","msg":"trace[870574351] linearizableReadLoop","detail":"{readStateIndex:15063; appliedIndex:15062; }","duration":"143.945865ms","start":"2026-07-22T22:33:44.823507Z","end":"2026-07-22T22:33:44.967453Z","steps":["trace[870574351] 'read index received' (duration: 143.70489ms)","trace[870574351] 'applied index is now lower than readState.Index' (duration: 240.115µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:33:44.9677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.18387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-22T22:33:44.967746Z","caller":"traceutil/trace.go:171","msg":"trace[1342858846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14563; }","duration":"144.284422ms","start":"2026-07-22T22:33:44.823448Z","end":"2026-07-22T22:33:44.967732Z","steps":["trace[1342858846] 'agreement among raft nodes before linearized reading' (duration: 144.131089ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:33:44.96785Z","caller":"traceutil/trace.go:171","msg":"trace[1920130715] transaction","detail":"{read_only:false; response_revision:14563; number_of_response:1; }","duration":"240.015401ms","start":"2026-07-22T22:33:44.727814Z","end":"2026-07-22T22:33:44.967829Z","steps":["trace[1920130715] 'process raft request' (duration: 239.46907ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:35:40.252768Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12949} {"level":"info","ts":"2026-07-22T22:35:40.328964Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12949,"took":"73.365207ms","hash":2278106053,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":30957568,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-22T22:35:40.32902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2278106053,"revision":12949,"compact-revision":10896} {"level":"warn","ts":"2026-07-22T22:38:53.826923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.89086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-jbfpr\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-07-22T22:38:53.827022Z","caller":"traceutil/trace.go:171","msg":"trace[821026002] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-jbfpr; range_end:; response_count:1; response_revision:16921; }","duration":"115.020622ms","start":"2026-07-22T22:38:53.711982Z","end":"2026-07-22T22:38:53.827003Z","steps":["trace[821026002] 'range keys from in-memory index tree' (duration: 114.658515ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:38:59.668679Z","caller":"traceutil/trace.go:171","msg":"trace[600893125] linearizableReadLoop","detail":"{readStateIndex:17552; appliedIndex:17551; }","duration":"173.123199ms","start":"2026-07-22T22:38:59.495534Z","end":"2026-07-22T22:38:59.668658Z","steps":["trace[600893125] 'read index received' (duration: 173.010487ms)","trace[600893125] 'applied index is now lower than readState.Index' (duration: 111.722µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:38:59.668813Z","caller":"traceutil/trace.go:171","msg":"trace[802167174] transaction","detail":"{read_only:false; response_revision:16973; number_of_response:1; }","duration":"232.107905ms","start":"2026-07-22T22:38:59.436692Z","end":"2026-07-22T22:38:59.6688Z","steps":["trace[802167174] 'process raft request' (duration: 231.84744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:38:59.669292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.421935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-22T22:38:59.669325Z","caller":"traceutil/trace.go:171","msg":"trace[692887159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16973; }","duration":"167.501817ms","start":"2026-07-22T22:38:59.501815Z","end":"2026-07-22T22:38:59.669317Z","steps":["trace[692887159] 'agreement among raft nodes before linearized reading' (duration: 167.372194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:38:59.669594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.036598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:38:59.669679Z","caller":"traceutil/trace.go:171","msg":"trace[648619326] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:16973; }","duration":"174.169411ms","start":"2026-07-22T22:38:59.495492Z","end":"2026-07-22T22:38:59.669661Z","steps":["trace[648619326] 'agreement among raft nodes before linearized reading' (duration: 173.643101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:38:59.881021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.754115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:38:59.881275Z","caller":"traceutil/trace.go:171","msg":"trace[110094200] transaction","detail":"{read_only:false; response_revision:16974; number_of_response:1; }","duration":"206.511007ms","start":"2026-07-22T22:38:59.67473Z","end":"2026-07-22T22:38:59.881242Z","steps":["trace[110094200] 'process raft request' (duration: 96.205331ms)","trace[110094200] 'compare' (duration: 109.425838ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:38:59.881386Z","caller":"traceutil/trace.go:171","msg":"trace[1530722400] linearizableReadLoop","detail":"{readStateIndex:17555; appliedIndex:17552; }","duration":"125.459253ms","start":"2026-07-22T22:38:59.755904Z","end":"2026-07-22T22:38:59.881363Z","steps":["trace[1530722400] 'read index received' (duration: 15.056765ms)","trace[1530722400] 'applied index is now lower than readState.Index' (duration: 110.401208ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:38:59.881476Z","caller":"traceutil/trace.go:171","msg":"trace[1267128480] transaction","detail":"{read_only:false; response_revision:16975; number_of_response:1; }","duration":"206.281282ms","start":"2026-07-22T22:38:59.675183Z","end":"2026-07-22T22:38:59.881465Z","steps":["trace[1267128480] 'process raft request' (duration: 205.983306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:38:59.881573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.668547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6m648\" ","response":"range_response_count:1 size:20762"} {"level":"info","ts":"2026-07-22T22:38:59.881628Z","caller":"traceutil/trace.go:171","msg":"trace[659635689] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6m648; range_end:; response_count:1; response_revision:16976; }","duration":"125.757739ms","start":"2026-07-22T22:38:59.755856Z","end":"2026-07-22T22:38:59.881613Z","steps":["trace[659635689] 'agreement among raft nodes before linearized reading' (duration: 125.586376ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:38:59.881611Z","caller":"traceutil/trace.go:171","msg":"trace[2110511304] transaction","detail":"{read_only:false; response_revision:16976; number_of_response:1; }","duration":"205.16136ms","start":"2026-07-22T22:38:59.676418Z","end":"2026-07-22T22:38:59.881579Z","steps":["trace[2110511304] 'process raft request' (duration: 204.875484ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:40:40.261019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15380} {"level":"info","ts":"2026-07-22T22:40:40.35539Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15380,"took":"91.161033ms","hash":2017925028,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":33763328,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-22T22:40:40.355464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2017925028,"revision":15380,"compact-revision":12949} {"level":"info","ts":"2026-07-22T22:43:16.161629Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-22T22:43:16.166801Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-22T22:43:16.167122Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-22T22:45:40.266604Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17996} {"level":"info","ts":"2026-07-22T22:45:40.378243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17996,"took":"107.912743ms","hash":1222929447,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":31956992,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-22T22:45:40.378316Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1222929447,"revision":17996,"compact-revision":15380} {"level":"info","ts":"2026-07-22T22:46:10.623808Z","caller":"traceutil/trace.go:171","msg":"trace[819259331] transaction","detail":"{read_only:false; response_revision:20634; number_of_response:1; }","duration":"151.128856ms","start":"2026-07-22T22:46:10.472534Z","end":"2026-07-22T22:46:10.623663Z","steps":["trace[819259331] 'process raft request' (duration: 150.960153ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:46:10.629117Z","caller":"traceutil/trace.go:171","msg":"trace[1970566146] transaction","detail":"{read_only:false; response_revision:20635; number_of_response:1; }","duration":"154.729019ms","start":"2026-07-22T22:46:10.474346Z","end":"2026-07-22T22:46:10.629075Z","steps":["trace[1970566146] 'process raft request' (duration: 154.451833ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:46:27.045447Z","caller":"traceutil/trace.go:171","msg":"trace[1107197928] transaction","detail":"{read_only:false; response_revision:20717; number_of_response:1; }","duration":"340.709848ms","start":"2026-07-22T22:46:26.704713Z","end":"2026-07-22T22:46:27.045422Z","steps":["trace[1107197928] 'process raft request' (duration: 274.20415ms)","trace[1107197928] 'compare' (duration: 66.342815ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:46:27.045654Z","caller":"traceutil/trace.go:171","msg":"trace[1558009197] linearizableReadLoop","detail":"{readStateIndex:21413; appliedIndex:21412; }","duration":"314.444151ms","start":"2026-07-22T22:46:26.731197Z","end":"2026-07-22T22:46:27.045641Z","steps":["trace[1558009197] 'read index received' (duration: 47.460199ms)","trace[1558009197] 'applied index is now lower than readState.Index' (duration: 266.983041ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:46:27.045721Z","caller":"traceutil/trace.go:171","msg":"trace[1294789959] transaction","detail":"{read_only:false; response_revision:20718; number_of_response:1; }","duration":"272.930073ms","start":"2026-07-22T22:46:26.772784Z","end":"2026-07-22T22:46:27.045714Z","steps":["trace[1294789959] 'process raft request' (duration: 272.597386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:46:27.045943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.750237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-22T22:46:27.045972Z","caller":"traceutil/trace.go:171","msg":"trace[1634820840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20718; }","duration":"314.823698ms","start":"2026-07-22T22:46:26.731142Z","end":"2026-07-22T22:46:27.045966Z","steps":["trace[1634820840] 'agreement among raft nodes before linearized reading' (duration: 314.710686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:46:27.045993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:46:26.731119Z","time spent":"314.868819ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T22:46:27.046133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.981898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-22T22:46:27.046156Z","caller":"traceutil/trace.go:171","msg":"trace[2104780338] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20718; }","duration":"187.04135ms","start":"2026-07-22T22:46:26.85911Z","end":"2026-07-22T22:46:27.046151Z","steps":["trace[2104780338] 'agreement among raft nodes before linearized reading' (duration: 186.974118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:46:27.046745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:46:26.704691Z","time spent":"340.82298ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:46:27.294954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.144459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:46:27.295327Z","caller":"traceutil/trace.go:171","msg":"trace[2022293838] linearizableReadLoop","detail":"{readStateIndex:21416; appliedIndex:21415; }","duration":"201.064036ms","start":"2026-07-22T22:46:27.094244Z","end":"2026-07-22T22:46:27.295308Z","steps":["trace[2022293838] 'read index received' (duration: 87.380915ms)","trace[2022293838] 'applied index is now lower than readState.Index' (duration: 113.681151ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:46:27.295519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.209958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-22T22:46:27.295557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.29775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-22T22:46:27.295615Z","caller":"traceutil/trace.go:171","msg":"trace[1629933765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20720; }","duration":"201.395293ms","start":"2026-07-22T22:46:27.094205Z","end":"2026-07-22T22:46:27.2956Z","steps":["trace[1629933765] 'agreement among raft nodes before linearized reading' (duration: 201.24835ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:46:27.295594Z","caller":"traceutil/trace.go:171","msg":"trace[645165160] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20720; }","duration":"152.3159ms","start":"2026-07-22T22:46:27.143265Z","end":"2026-07-22T22:46:27.295581Z","steps":["trace[645165160] 'agreement among raft nodes before linearized reading' (duration: 152.207048ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:46:27.295494Z","caller":"traceutil/trace.go:171","msg":"trace[1757598585] transaction","detail":"{read_only:false; response_revision:20720; number_of_response:1; }","duration":"240.90446ms","start":"2026-07-22T22:46:27.054552Z","end":"2026-07-22T22:46:27.295457Z","steps":["trace[1757598585] 'process raft request' (duration: 127.160518ms)","trace[1757598585] 'compare' (duration: 112.600789ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:46:27.585906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.581561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:46:27.586006Z","caller":"traceutil/trace.go:171","msg":"trace[1597041351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20721; }","duration":"142.742115ms","start":"2026-07-22T22:46:27.443245Z","end":"2026-07-22T22:46:27.585987Z","steps":["trace[1597041351] 'range keys from in-memory index tree' (duration: 142.50055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:50:03.444671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.926782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:50:03.444903Z","caller":"traceutil/trace.go:171","msg":"trace[2083921665] transaction","detail":"{read_only:false; response_revision:23009; number_of_response:1; }","duration":"224.478154ms","start":"2026-07-22T22:50:03.220406Z","end":"2026-07-22T22:50:03.444884Z","steps":["trace[2083921665] 'process raft request' (duration: 122.241065ms)","trace[2083921665] 'compare' (duration: 101.78935ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:50:03.445076Z","caller":"traceutil/trace.go:171","msg":"trace[688537176] transaction","detail":"{read_only:false; response_revision:23010; number_of_response:1; }","duration":"132.550477ms","start":"2026-07-22T22:50:03.312517Z","end":"2026-07-22T22:50:03.445067Z","steps":["trace[688537176] 'process raft request' (duration: 132.270922ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:50:03.637908Z","caller":"traceutil/trace.go:171","msg":"trace[979241096] transaction","detail":"{read_only:false; response_revision:23011; number_of_response:1; }","duration":"126.583076ms","start":"2026-07-22T22:50:03.511293Z","end":"2026-07-22T22:50:03.637876Z","steps":["trace[979241096] 'process raft request' (duration: 126.323291ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:50:40.273414Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20477} {"level":"info","ts":"2026-07-22T22:50:40.394147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20477,"took":"116.646421ms","hash":2006825311,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":44191744,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-22T22:50:40.394208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2006825311,"revision":20477,"compact-revision":17996} {"level":"info","ts":"2026-07-22T22:53:11.89382Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000064be.wal"} {"level":"info","ts":"2026-07-22T22:53:23.395136Z","caller":"traceutil/trace.go:171","msg":"trace[316963337] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"130.34168ms","start":"2026-07-22T22:53:23.264752Z","end":"2026-07-22T22:53:23.395093Z","steps":["trace[316963337] 'process raft request' (duration: 130.041334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:23.659618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:53:23.659861Z","caller":"traceutil/trace.go:171","msg":"trace[2116650137] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"259.829822ms","start":"2026-07-22T22:53:23.400011Z","end":"2026-07-22T22:53:23.65984Z","steps":["trace[2116650137] 'process raft request' (duration: 259.788902ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:23.660056Z","caller":"traceutil/trace.go:171","msg":"trace[1389265841] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"341.147492ms","start":"2026-07-22T22:53:23.318883Z","end":"2026-07-22T22:53:23.660031Z","steps":["trace[1389265841] 'process raft request' (duration: 127.462642ms)","trace[1389265841] 'compare' (duration: 213.015297ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:23.66015Z","caller":"traceutil/trace.go:171","msg":"trace[103263728] linearizableReadLoop","detail":"{readStateIndex:25943; appliedIndex:25941; }","duration":"265.059799ms","start":"2026-07-22T22:53:23.395056Z","end":"2026-07-22T22:53:23.660115Z","steps":["trace[103263728] 'read index received' (duration: 51.299567ms)","trace[103263728] 'applied index is now lower than readState.Index' (duration: 213.755792ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:23.660175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:23.318866Z","time spent":"341.245184ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:53:23.660206Z","caller":"traceutil/trace.go:171","msg":"trace[1912612049] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"288.089069ms","start":"2026-07-22T22:53:23.372082Z","end":"2026-07-22T22:53:23.660171Z","steps":["trace[1912612049] 'process raft request' (duration: 287.65249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:23.660411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.104805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-22T22:53:23.660519Z","caller":"traceutil/trace.go:171","msg":"trace[1188877930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25122; }","duration":"277.246077ms","start":"2026-07-22T22:53:23.383234Z","end":"2026-07-22T22:53:23.66048Z","steps":["trace[1188877930] 'agreement among raft nodes before linearized reading' (duration: 276.971852ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:23.66068Z","caller":"traceutil/trace.go:171","msg":"trace[970264520] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"180.27163ms","start":"2026-07-22T22:53:23.480394Z","end":"2026-07-22T22:53:23.660665Z","steps":["trace[970264520] 'process raft request' (duration: 180.168627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:23.660698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.778274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:23.660746Z","caller":"traceutil/trace.go:171","msg":"trace[286539691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25123; }","duration":"216.859546ms","start":"2026-07-22T22:53:23.443873Z","end":"2026-07-22T22:53:23.660733Z","steps":["trace[286539691] 'agreement among raft nodes before linearized reading' (duration: 216.758203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:23.660854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.152413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:23.660889Z","caller":"traceutil/trace.go:171","msg":"trace[732780288] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25123; }","duration":"146.225115ms","start":"2026-07-22T22:53:23.514653Z","end":"2026-07-22T22:53:23.660878Z","steps":["trace[732780288] 'agreement among raft nodes before linearized reading' (duration: 146.164024ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:24.10995Z","caller":"traceutil/trace.go:171","msg":"trace[616224469] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"181.154657ms","start":"2026-07-22T22:53:23.928777Z","end":"2026-07-22T22:53:24.109931Z","steps":["trace[616224469] 'process raft request' (duration: 180.511314ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:27.782789Z","caller":"traceutil/trace.go:171","msg":"trace[403345719] linearizableReadLoop","detail":"{readStateIndex:25975; appliedIndex:25974; }","duration":"105.698457ms","start":"2026-07-22T22:53:27.677071Z","end":"2026-07-22T22:53:27.782769Z","steps":["trace[403345719] 'read index received' (duration: 98.771235ms)","trace[403345719] 'applied index is now lower than readState.Index' (duration: 6.925832ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:27.782878Z","caller":"traceutil/trace.go:171","msg":"trace[953681300] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"109.376272ms","start":"2026-07-22T22:53:27.673494Z","end":"2026-07-22T22:53:27.78287Z","steps":["trace[953681300] 'process raft request' (duration: 102.181685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:27.783085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.002513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-22T22:53:27.783111Z","caller":"traceutil/trace.go:171","msg":"trace[951683314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25152; }","duration":"106.097645ms","start":"2026-07-22T22:53:27.677008Z","end":"2026-07-22T22:53:27.783105Z","steps":["trace[951683314] 'agreement among raft nodes before linearized reading' (duration: 105.992343ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:27.783564Z","caller":"traceutil/trace.go:171","msg":"trace[930176635] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"106.142456ms","start":"2026-07-22T22:53:27.677414Z","end":"2026-07-22T22:53:27.783557Z","steps":["trace[930176635] 'process raft request' (duration: 105.893421ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:28.02307Z","caller":"traceutil/trace.go:171","msg":"trace[103390972] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"168.270754ms","start":"2026-07-22T22:53:27.854777Z","end":"2026-07-22T22:53:28.023048Z","steps":["trace[103390972] 'process raft request' (duration: 136.886033ms)","trace[103390972] 'compare' (duration: 31.163126ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:28.293463Z","caller":"traceutil/trace.go:171","msg":"trace[972555628] linearizableReadLoop","detail":"{readStateIndex:25980; appliedIndex:25979; }","duration":"110.101256ms","start":"2026-07-22T22:53:28.183342Z","end":"2026-07-22T22:53:28.293443Z","steps":["trace[972555628] 'read index received' (duration: 109.959093ms)","trace[972555628] 'applied index is now lower than readState.Index' (duration: 141.543µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:28.293696Z","caller":"traceutil/trace.go:171","msg":"trace[919935861] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"111.695159ms","start":"2026-07-22T22:53:28.181989Z","end":"2026-07-22T22:53:28.293684Z","steps":["trace[919935861] 'process raft request' (duration: 111.340752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:28.293905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.556935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-22T22:53:28.293927Z","caller":"traceutil/trace.go:171","msg":"trace[747731285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25157; }","duration":"110.657968ms","start":"2026-07-22T22:53:28.183263Z","end":"2026-07-22T22:53:28.293921Z","steps":["trace[747731285] 'agreement among raft nodes before linearized reading' (duration: 110.556606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:28.294212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.957734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-22T22:53:28.294298Z","caller":"traceutil/trace.go:171","msg":"trace[1029626753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25157; }","duration":"109.075536ms","start":"2026-07-22T22:53:28.185201Z","end":"2026-07-22T22:53:28.294276Z","steps":["trace[1029626753] 'agreement among raft nodes before linearized reading' (duration: 108.81533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:28.585149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.781276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:53:28.585368Z","caller":"traceutil/trace.go:171","msg":"trace[1203610971] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"281.689658ms","start":"2026-07-22T22:53:28.303656Z","end":"2026-07-22T22:53:28.585345Z","steps":["trace[1203610971] 'process raft request' (duration: 281.629167ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:28.585411Z","caller":"traceutil/trace.go:171","msg":"trace[1689387904] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"287.548307ms","start":"2026-07-22T22:53:28.297817Z","end":"2026-07-22T22:53:28.585365Z","steps":["trace[1689387904] 'process raft request' (duration: 136.461935ms)","trace[1689387904] 'compare' (duration: 150.640413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:28.922291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.24007ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-22T22:53:28.932688Z","caller":"traceutil/trace.go:171","msg":"trace[189068104] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"290.438947ms","start":"2026-07-22T22:53:28.642221Z","end":"2026-07-22T22:53:28.93266Z","steps":["trace[189068104] 'process raft request' (duration: 290.180792ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:28.932836Z","caller":"traceutil/trace.go:171","msg":"trace[417615186] linearizableReadLoop","detail":"{readStateIndex:25985; appliedIndex:25983; }","duration":"162.899134ms","start":"2026-07-22T22:53:28.769916Z","end":"2026-07-22T22:53:28.932815Z","steps":["trace[417615186] 'read index received' (duration: 162.453385ms)","trace[417615186] 'applied index is now lower than readState.Index' (duration: 443.769µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:28.932866Z","caller":"traceutil/trace.go:171","msg":"trace[1534449219] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"266.996759ms","start":"2026-07-22T22:53:28.665842Z","end":"2026-07-22T22:53:28.932839Z","steps":["trace[1534449219] 'process raft request' (duration: 266.735013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:28.933034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.11969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-22T22:53:28.933082Z","caller":"traceutil/trace.go:171","msg":"trace[1129881930] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25161; }","duration":"163.221032ms","start":"2026-07-22T22:53:28.769848Z","end":"2026-07-22T22:53:28.933069Z","steps":["trace[1129881930] 'agreement among raft nodes before linearized reading' (duration: 163.065349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:29.151844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.484793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:53:29.152105Z","caller":"traceutil/trace.go:171","msg":"trace[1738696438] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"232.558366ms","start":"2026-07-22T22:53:28.919505Z","end":"2026-07-22T22:53:29.152064Z","steps":["trace[1738696438] 'process raft request' (duration: 127.766016ms)","trace[1738696438] 'compare' (duration: 103.930211ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:29.152238Z","caller":"traceutil/trace.go:171","msg":"trace[558536039] linearizableReadLoop","detail":"{readStateIndex:25986; appliedIndex:25985; }","duration":"219.322866ms","start":"2026-07-22T22:53:28.932887Z","end":"2026-07-22T22:53:29.152209Z","steps":["trace[558536039] 'read index received' (duration: 114.336083ms)","trace[558536039] 'applied index is now lower than readState.Index' (duration: 104.984463ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:29.152503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.91029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-22T22:53:29.15255Z","caller":"traceutil/trace.go:171","msg":"trace[437698162] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25162; }","duration":"224.999021ms","start":"2026-07-22T22:53:28.927536Z","end":"2026-07-22T22:53:29.152535Z","steps":["trace[437698162] 'agreement among raft nodes before linearized reading' (duration: 224.812457ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:29.152686Z","caller":"traceutil/trace.go:171","msg":"trace[432129809] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"215.677051ms","start":"2026-07-22T22:53:28.936971Z","end":"2026-07-22T22:53:29.152648Z","steps":["trace[432129809] 'process raft request' (duration: 215.160681ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:29.449628Z","caller":"traceutil/trace.go:171","msg":"trace[265746461] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"292.493438ms","start":"2026-07-22T22:53:29.157109Z","end":"2026-07-22T22:53:29.449603Z","steps":["trace[265746461] 'process raft request' (duration: 292.274354ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:29.450737Z","caller":"traceutil/trace.go:171","msg":"trace[292796892] linearizableReadLoop","detail":"{readStateIndex:25989; appliedIndex:25989; }","duration":"274.525392ms","start":"2026-07-22T22:53:29.176159Z","end":"2026-07-22T22:53:29.450685Z","steps":["trace[292796892] 'read index received' (duration: 274.518762ms)","trace[292796892] 'applied index is now lower than readState.Index' (duration: 5.19µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:29.450953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.729362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-22T22:53:29.450986Z","caller":"traceutil/trace.go:171","msg":"trace[971801741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25165; }","duration":"263.802923ms","start":"2026-07-22T22:53:29.187176Z","end":"2026-07-22T22:53:29.450979Z","steps":["trace[971801741] 'agreement among raft nodes before linearized reading' (duration: 263.66394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:29.451198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.033543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:29.451228Z","caller":"traceutil/trace.go:171","msg":"trace[1049722052] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25165; }","duration":"275.100915ms","start":"2026-07-22T22:53:29.176116Z","end":"2026-07-22T22:53:29.451217Z","steps":["trace[1049722052] 'agreement among raft nodes before linearized reading' (duration: 275.051404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:29.712393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.756452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:53:29.712692Z","caller":"traceutil/trace.go:171","msg":"trace[1622425147] linearizableReadLoop","detail":"{readStateIndex:25991; appliedIndex:25989; }","duration":"150.133514ms","start":"2026-07-22T22:53:29.562537Z","end":"2026-07-22T22:53:29.712671Z","steps":["trace[1622425147] 'read index received' (duration: 141.239503ms)","trace[1622425147] 'applied index is now lower than readState.Index' (duration: 8.892821ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:29.712782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.240776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:29.712823Z","caller":"traceutil/trace.go:171","msg":"trace[1214782822] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25167; }","duration":"150.320708ms","start":"2026-07-22T22:53:29.562489Z","end":"2026-07-22T22:53:29.712809Z","steps":["trace[1214782822] 'agreement among raft nodes before linearized reading' (duration: 150.247786ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:29.713081Z","caller":"traceutil/trace.go:171","msg":"trace[352341565] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"252.403621ms","start":"2026-07-22T22:53:29.4606Z","end":"2026-07-22T22:53:29.713003Z","steps":["trace[352341565] 'process raft request' (duration: 251.939401ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:29.71334Z","caller":"traceutil/trace.go:171","msg":"trace[285804509] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"256.001504ms","start":"2026-07-22T22:53:29.457296Z","end":"2026-07-22T22:53:29.713298Z","steps":["trace[285804509] 'process raft request' (duration: 18.206061ms)","trace[285804509] 'compare' (duration: 236.348124ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:30.037728Z","caller":"traceutil/trace.go:171","msg":"trace[2076143888] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"174.949561ms","start":"2026-07-22T22:53:29.862741Z","end":"2026-07-22T22:53:30.03769Z","steps":["trace[2076143888] 'process raft request' (duration: 160.765991ms)","trace[2076143888] 'compare' (duration: 13.861463ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:30.574608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.590884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-07-22T22:53:30.574728Z","caller":"traceutil/trace.go:171","msg":"trace[452545336] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25173; }","duration":"103.811909ms","start":"2026-07-22T22:53:30.470891Z","end":"2026-07-22T22:53:30.574703Z","steps":["trace[452545336] 'range keys from in-memory index tree' (duration: 103.338698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:30.886229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.212663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:53:30.886593Z","caller":"traceutil/trace.go:171","msg":"trace[2104739434] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"254.001244ms","start":"2026-07-22T22:53:30.632321Z","end":"2026-07-22T22:53:30.886322Z","steps":["trace[2104739434] 'process raft request' (duration: 61.599487ms)","trace[2104739434] 'compare' (duration: 191.710413ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:30.892855Z","caller":"traceutil/trace.go:171","msg":"trace[617835968] transaction","detail":"{read_only:false; response_revision:25176; number_of_response:1; }","duration":"259.330822ms","start":"2026-07-22T22:53:30.633429Z","end":"2026-07-22T22:53:30.89276Z","steps":["trace[617835968] 'process raft request' (duration: 259.094007ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:30.967687Z","caller":"traceutil/trace.go:171","msg":"trace[631831065] linearizableReadLoop","detail":"{readStateIndex:26001; appliedIndex:26000; }","duration":"204.120596ms","start":"2026-07-22T22:53:30.763543Z","end":"2026-07-22T22:53:30.967663Z","steps":["trace[631831065] 'read index received' (duration: 129.523923ms)","trace[631831065] 'applied index is now lower than readState.Index' (duration: 74.595993ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:30.967742Z","caller":"traceutil/trace.go:171","msg":"trace[174109421] transaction","detail":"{read_only:false; response_revision:25177; number_of_response:1; }","duration":"260.135699ms","start":"2026-07-22T22:53:30.707576Z","end":"2026-07-22T22:53:30.967712Z","steps":["trace[174109421] 'process raft request' (duration: 259.952845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:30.967906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.35148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-22T22:53:30.967934Z","caller":"traceutil/trace.go:171","msg":"trace[1111218603] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25177; }","duration":"204.433002ms","start":"2026-07-22T22:53:30.763492Z","end":"2026-07-22T22:53:30.967925Z","steps":["trace[1111218603] 'agreement among raft nodes before linearized reading' (duration: 204.287609ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:31.063206Z","caller":"traceutil/trace.go:171","msg":"trace[1105023747] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"125.139544ms","start":"2026-07-22T22:53:30.938041Z","end":"2026-07-22T22:53:31.06318Z","steps":["trace[1105023747] 'process raft request' (duration: 118.528509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:31.06326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.205049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:31.063327Z","caller":"traceutil/trace.go:171","msg":"trace[1984024230] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25178; }","duration":"135.294931ms","start":"2026-07-22T22:53:30.928015Z","end":"2026-07-22T22:53:31.06331Z","steps":["trace[1984024230] 'agreement among raft nodes before linearized reading' (duration: 135.135718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:31.351035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.229097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:53:31.351596Z","caller":"traceutil/trace.go:171","msg":"trace[1847079005] transaction","detail":"{read_only:false; response_revision:25179; number_of_response:1; }","duration":"193.788394ms","start":"2026-07-22T22:53:31.157783Z","end":"2026-07-22T22:53:31.351571Z","steps":["trace[1847079005] 'process raft request' (duration: 41.938086ms)","trace[1847079005] 'compare' (duration: 150.968931ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:31.351763Z","caller":"traceutil/trace.go:171","msg":"trace[193986542] linearizableReadLoop","detail":"{readStateIndex:26003; appliedIndex:26002; }","duration":"193.420678ms","start":"2026-07-22T22:53:31.158259Z","end":"2026-07-22T22:53:31.351679Z","steps":["trace[193986542] 'read index received' (duration: 41.476137ms)","trace[193986542] 'applied index is now lower than readState.Index' (duration: 151.941611ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:31.351926Z","caller":"traceutil/trace.go:171","msg":"trace[179160769] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"192.637981ms","start":"2026-07-22T22:53:31.159277Z","end":"2026-07-22T22:53:31.351915Z","steps":["trace[179160769] 'process raft request' (duration: 192.132861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:31.351964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.682383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-07-22T22:53:31.352058Z","caller":"traceutil/trace.go:171","msg":"trace[608729395] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25179; }","duration":"193.804335ms","start":"2026-07-22T22:53:31.158231Z","end":"2026-07-22T22:53:31.352035Z","steps":["trace[608729395] 'agreement among raft nodes before linearized reading' (duration: 193.5495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:31.652305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.722274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-22T22:53:31.6524Z","caller":"traceutil/trace.go:171","msg":"trace[2128508403] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25180; }","duration":"197.853866ms","start":"2026-07-22T22:53:31.454517Z","end":"2026-07-22T22:53:31.652371Z","steps":["trace[2128508403] 'range keys from in-memory index tree' (duration: 197.52954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:31.652704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.198459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:31.653133Z","caller":"traceutil/trace.go:171","msg":"trace[82755228] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25180; }","duration":"113.519196ms","start":"2026-07-22T22:53:31.539242Z","end":"2026-07-22T22:53:31.652761Z","steps":["trace[82755228] 'count revisions from in-memory index tree' (duration: 112.631378ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:31.91878Z","caller":"traceutil/trace.go:171","msg":"trace[815843076] linearizableReadLoop","detail":"{readStateIndex:26005; appliedIndex:26004; }","duration":"200.976901ms","start":"2026-07-22T22:53:31.717773Z","end":"2026-07-22T22:53:31.918749Z","steps":["trace[815843076] 'read index received' (duration: 200.639324ms)","trace[815843076] 'applied index is now lower than readState.Index' (duration: 335.827µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:31.919114Z","caller":"traceutil/trace.go:171","msg":"trace[1655169486] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"261.380075ms","start":"2026-07-22T22:53:31.657705Z","end":"2026-07-22T22:53:31.919085Z","steps":["trace[1655169486] 'process raft request' (duration: 260.64339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:31.919237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.446261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-22T22:53:31.919314Z","caller":"traceutil/trace.go:171","msg":"trace[1116938337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25181; }","duration":"201.575634ms","start":"2026-07-22T22:53:31.717722Z","end":"2026-07-22T22:53:31.919298Z","steps":["trace[1116938337] 'agreement among raft nodes before linearized reading' (duration: 201.322807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:31.951857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.403953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-22T22:53:31.951928Z","caller":"traceutil/trace.go:171","msg":"trace[1698283350] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25184; }","duration":"233.515765ms","start":"2026-07-22T22:53:31.718394Z","end":"2026-07-22T22:53:31.95191Z","steps":["trace[1698283350] 'agreement among raft nodes before linearized reading' (duration: 233.344522ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:32.313076Z","caller":"traceutil/trace.go:171","msg":"trace[2059757521] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"267.758314ms","start":"2026-07-22T22:53:32.045274Z","end":"2026-07-22T22:53:32.313033Z","steps":["trace[2059757521] 'process raft request' (duration: 267.508649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:32.582944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.938254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:53:32.58306Z","caller":"traceutil/trace.go:171","msg":"trace[1146774955] linearizableReadLoop","detail":"{readStateIndex:26012; appliedIndex:26011; }","duration":"138.476637ms","start":"2026-07-22T22:53:32.444565Z","end":"2026-07-22T22:53:32.583042Z","steps":["trace[1146774955] 'read index received' (duration: 97.602µs)","trace[1146774955] 'applied index is now lower than readState.Index' (duration: 138.377584ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:32.583169Z","caller":"traceutil/trace.go:171","msg":"trace[9360756] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"215.906206ms","start":"2026-07-22T22:53:32.367228Z","end":"2026-07-22T22:53:32.583134Z","steps":["trace[9360756] 'process raft request' (duration: 53.687816ms)","trace[9360756] 'compare' (duration: 161.815991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:32.583209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.65624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:32.583249Z","caller":"traceutil/trace.go:171","msg":"trace[1005826905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25188; }","duration":"138.750461ms","start":"2026-07-22T22:53:32.444488Z","end":"2026-07-22T22:53:32.583238Z","steps":["trace[1005826905] 'agreement among raft nodes before linearized reading' (duration: 138.68086ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:33.052699Z","caller":"traceutil/trace.go:171","msg":"trace[1087756493] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"144.279644ms","start":"2026-07-22T22:53:32.908394Z","end":"2026-07-22T22:53:33.052674Z","steps":["trace[1087756493] 'process raft request' (duration: 111.796402ms)","trace[1087756493] 'compare' (duration: 32.37645ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:33.213916Z","caller":"traceutil/trace.go:171","msg":"trace[1321345647] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"145.193203ms","start":"2026-07-22T22:53:33.068704Z","end":"2026-07-22T22:53:33.213897Z","steps":["trace[1321345647] 'process raft request' (duration: 143.455647ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:42.279178Z","caller":"traceutil/trace.go:171","msg":"trace[1561056529] linearizableReadLoop","detail":"{readStateIndex:26078; appliedIndex:26077; }","duration":"297.864289ms","start":"2026-07-22T22:53:41.98129Z","end":"2026-07-22T22:53:42.279154Z","steps":["trace[1561056529] 'read index received' (duration: 297.822978ms)","trace[1561056529] 'applied index is now lower than readState.Index' (duration: 40.311µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:42.279258Z","caller":"traceutil/trace.go:171","msg":"trace[674161614] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"308.817632ms","start":"2026-07-22T22:53:41.970408Z","end":"2026-07-22T22:53:42.279226Z","steps":["trace[674161614] 'process raft request' (duration: 308.580278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.279484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:41.970399Z","time spent":"309.002096ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:53:42.279478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.153136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:42.280105Z","caller":"traceutil/trace.go:171","msg":"trace[294228997] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25252; }","duration":"298.841609ms","start":"2026-07-22T22:53:41.981236Z","end":"2026-07-22T22:53:42.280078Z","steps":["trace[294228997] 'agreement among raft nodes before linearized reading' (duration: 298.027572ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:42.652625Z","caller":"traceutil/trace.go:171","msg":"trace[383085497] linearizableReadLoop","detail":"{readStateIndex:26081; appliedIndex:26078; }","duration":"373.340268ms","start":"2026-07-22T22:53:42.279254Z","end":"2026-07-22T22:53:42.652594Z","steps":["trace[383085497] 'read index received' (duration: 372.90065ms)","trace[383085497] 'applied index is now lower than readState.Index' (duration: 438.108µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:42.652953Z","caller":"traceutil/trace.go:171","msg":"trace[1102887513] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"496.243777ms","start":"2026-07-22T22:53:42.156667Z","end":"2026-07-22T22:53:42.652911Z","steps":["trace[1102887513] 'process raft request' (duration: 495.848429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.653047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.089454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-22T22:53:42.653092Z","caller":"traceutil/trace.go:171","msg":"trace[2062381266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25255; }","duration":"646.162716ms","start":"2026-07-22T22:53:42.006914Z","end":"2026-07-22T22:53:42.653077Z","steps":["trace[2062381266] 'agreement among raft nodes before linearized reading' (duration: 646.016593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.653127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.006905Z","time spent":"646.214127ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T22:53:42.653124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.156646Z","time spent":"496.39812ms","remote":"127.0.0.1:45148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7681,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:53:42.653351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.891391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-22T22:53:42.65337Z","caller":"traceutil/trace.go:171","msg":"trace[1182024646] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"656.279553ms","start":"2026-07-22T22:53:41.997076Z","end":"2026-07-22T22:53:42.653356Z","steps":["trace[1182024646] 'process raft request' (duration: 655.176411ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:42.653385Z","caller":"traceutil/trace.go:171","msg":"trace[2096027688] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25255; }","duration":"646.960003ms","start":"2026-07-22T22:53:42.006411Z","end":"2026-07-22T22:53:42.653371Z","steps":["trace[2096027688] 'agreement among raft nodes before linearized reading' (duration: 646.874681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.653414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.006395Z","time spent":"647.012174ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-22T22:53:42.653428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:41.997058Z","time spent":"656.338263ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:53:42.653513Z","caller":"traceutil/trace.go:171","msg":"trace[108671834] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"653.70081ms","start":"2026-07-22T22:53:41.999802Z","end":"2026-07-22T22:53:42.653503Z","steps":["trace[108671834] 'process raft request' (duration: 652.649829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.653561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:41.999791Z","time spent":"653.74314ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:53:42.653632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.783119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-22T22:53:42.653661Z","caller":"traceutil/trace.go:171","msg":"trace[263875113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25255; }","duration":"648.848171ms","start":"2026-07-22T22:53:42.004804Z","end":"2026-07-22T22:53:42.653652Z","steps":["trace[263875113] 'agreement among raft nodes before linearized reading' (duration: 648.765309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.653688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.004779Z","time spent":"648.902751ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T22:53:42.664137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.748594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:42.664187Z","caller":"traceutil/trace.go:171","msg":"trace[402318935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25256; }","duration":"220.841387ms","start":"2026-07-22T22:53:42.443335Z","end":"2026-07-22T22:53:42.664177Z","steps":["trace[402318935] 'agreement among raft nodes before linearized reading' (duration: 220.748575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.664197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.550564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:42.664239Z","caller":"traceutil/trace.go:171","msg":"trace[822110761] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25256; }","duration":"375.628616ms","start":"2026-07-22T22:53:42.288598Z","end":"2026-07-22T22:53:42.664226Z","steps":["trace[822110761] 'agreement among raft nodes before linearized reading' (duration: 375.553174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.66424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.410894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-22T22:53:42.664263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.288581Z","time spent":"375.675826ms","remote":"127.0.0.1:33704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"info","ts":"2026-07-22T22:53:42.664295Z","caller":"traceutil/trace.go:171","msg":"trace[145848884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25256; }","duration":"309.500836ms","start":"2026-07-22T22:53:42.354776Z","end":"2026-07-22T22:53:42.664277Z","steps":["trace[145848884] 'agreement among raft nodes before linearized reading' (duration: 309.349313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.664333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.354755Z","time spent":"309.569167ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-22T22:53:42.966946Z","caller":"traceutil/trace.go:171","msg":"trace[377279311] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"307.461165ms","start":"2026-07-22T22:53:42.659459Z","end":"2026-07-22T22:53:42.966921Z","steps":["trace[377279311] 'process raft request' (duration: 307.290521ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:42.967023Z","caller":"traceutil/trace.go:171","msg":"trace[1470534130] linearizableReadLoop","detail":"{readStateIndex:26085; appliedIndex:26084; }","duration":"302.983442ms","start":"2026-07-22T22:53:42.664032Z","end":"2026-07-22T22:53:42.967015Z","steps":["trace[1470534130] 'read index received' (duration: 302.942382ms)","trace[1470534130] 'applied index is now lower than readState.Index' (duration: 40.29µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:42.967079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.659434Z","time spent":"307.576427ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:53:42.967098Z","caller":"traceutil/trace.go:171","msg":"trace[2125319314] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"307.422433ms","start":"2026-07-22T22:53:42.659651Z","end":"2026-07-22T22:53:42.967073Z","steps":["trace[2125319314] 'process raft request' (duration: 307.250579ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:42.96695Z","caller":"traceutil/trace.go:171","msg":"trace[442844063] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"307.364612ms","start":"2026-07-22T22:53:42.659566Z","end":"2026-07-22T22:53:42.96693Z","steps":["trace[442844063] 'process raft request' (duration: 307.307601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.96717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.837701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:17940"} {"level":"info","ts":"2026-07-22T22:53:42.967194Z","caller":"traceutil/trace.go:171","msg":"trace[640793966] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25259; }","duration":"306.902923ms","start":"2026-07-22T22:53:42.660285Z","end":"2026-07-22T22:53:42.967187Z","steps":["trace[640793966] 'agreement among raft nodes before linearized reading' (duration: 306.785761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.967196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.659538Z","time spent":"307.633128ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:53:42.96722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.660271Z","time spent":"306.943243ms","remote":"127.0.0.1:33788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":17963,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-07-22T22:53:42.967224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.659642Z","time spent":"307.511114ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:53:42.978519Z","caller":"traceutil/trace.go:171","msg":"trace[1510772398] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"311.109009ms","start":"2026-07-22T22:53:42.667386Z","end":"2026-07-22T22:53:42.978495Z","steps":["trace[1510772398] 'process raft request' (duration: 310.995087ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:42.97853Z","caller":"traceutil/trace.go:171","msg":"trace[1079618403] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"312.746761ms","start":"2026-07-22T22:53:42.665767Z","end":"2026-07-22T22:53:42.978514Z","steps":["trace[1079618403] 'process raft request' (duration: 312.468206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.978628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.56417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-22T22:53:42.978667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.665749Z","time spent":"312.881234ms","remote":"127.0.0.1:59246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1561,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:53:42.97868Z","caller":"traceutil/trace.go:171","msg":"trace[2088171226] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:25261; }","duration":"220.642962ms","start":"2026-07-22T22:53:42.758019Z","end":"2026-07-22T22:53:42.978662Z","steps":["trace[2088171226] 'agreement among raft nodes before linearized reading' (duration: 220.54204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.978512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.59544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-22T22:53:42.978698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.667378Z","time spent":"311.255232ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:53:42.978746Z","caller":"traceutil/trace.go:171","msg":"trace[1132220128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25261; }","duration":"313.847574ms","start":"2026-07-22T22:53:42.664876Z","end":"2026-07-22T22:53:42.978724Z","steps":["trace[1132220128] 'agreement among raft nodes before linearized reading' (duration: 313.569089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:42.978811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.664869Z","time spent":"313.920286ms","remote":"127.0.0.1:33632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:53:43.484956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356766911965066032,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:53:43.572344Z","caller":"traceutil/trace.go:171","msg":"trace[765932979] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"589.260075ms","start":"2026-07-22T22:53:42.983056Z","end":"2026-07-22T22:53:43.572316Z","steps":["trace[765932979] 'process raft request' (duration: 589.110602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:43.572487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.983041Z","time spent":"589.373047ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:53:44.260983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.604322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:53:44.261336Z","caller":"traceutil/trace.go:171","msg":"trace[695885678] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"305.693498ms","start":"2026-07-22T22:53:43.955619Z","end":"2026-07-22T22:53:44.261313Z","steps":["trace[695885678] 'process raft request' (duration: 305.626167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.261454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.955597Z","time spent":"305.802231ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:53:44.26149Z","caller":"traceutil/trace.go:171","msg":"trace[1355957554] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"871.70291ms","start":"2026-07-22T22:53:43.389745Z","end":"2026-07-22T22:53:44.261448Z","steps":["trace[1355957554] 'process raft request' (duration: 871.357923ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:44.261561Z","caller":"traceutil/trace.go:171","msg":"trace[556469977] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"871.566276ms","start":"2026-07-22T22:53:43.389969Z","end":"2026-07-22T22:53:44.261535Z","steps":["trace[556469977] 'process raft request' (duration: 871.217919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.261631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.389718Z","time spent":"871.834252ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:53:44.261618Z","caller":"traceutil/trace.go:171","msg":"trace[1244966507] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"1.002348304s","start":"2026-07-22T22:53:43.259228Z","end":"2026-07-22T22:53:44.261577Z","steps":["trace[1244966507] 'process raft request' (duration: 536.053678ms)","trace[1244966507] 'compare' (duration: 465.47607ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:44.261676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.389954Z","time spent":"871.665518ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:53:44.261791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.259205Z","time spent":"1.002499128s","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:53:44.261883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27747924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1579"} {"level":"info","ts":"2026-07-22T22:53:44.261961Z","caller":"traceutil/trace.go:171","msg":"trace[105012047] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25266; }","duration":"1.277605204s","start":"2026-07-22T22:53:42.984338Z","end":"2026-07-22T22:53:44.261943Z","steps":["trace[105012047] 'agreement among raft nodes before linearized reading' (duration: 1.277360658s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.261998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:42.984319Z","time spent":"1.277670235s","remote":"127.0.0.1:59246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1602,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-07-22T22:53:44.261825Z","caller":"traceutil/trace.go:171","msg":"trace[76523188] linearizableReadLoop","detail":"{readStateIndex:26091; appliedIndex:26087; }","duration":"1.277210174s","start":"2026-07-22T22:53:42.984381Z","end":"2026-07-22T22:53:44.261591Z","steps":["trace[76523188] 'read index received' (duration: 587.800855ms)","trace[76523188] 'applied index is now lower than readState.Index' (duration: 689.404069ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:44.262329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.012396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:44.262371Z","caller":"traceutil/trace.go:171","msg":"trace[692894205] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25266; }","duration":"579.094278ms","start":"2026-07-22T22:53:43.683265Z","end":"2026-07-22T22:53:44.262359Z","steps":["trace[692894205] 'agreement among raft nodes before linearized reading' (duration: 579.016006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.262405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.683243Z","time spent":"579.15411ms","remote":"127.0.0.1:54572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:53:44.262327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249342356s","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:6"} {"level":"info","ts":"2026-07-22T22:53:44.26254Z","caller":"traceutil/trace.go:171","msg":"trace[1594619525] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25266; }","duration":"1.249583741s","start":"2026-07-22T22:53:43.012935Z","end":"2026-07-22T22:53:44.262519Z","steps":["trace[1594619525] 'agreement among raft nodes before linearized reading' (duration: 1.249341776s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.262651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004420028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-07-22T22:53:44.262704Z","caller":"traceutil/trace.go:171","msg":"trace[870411220] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25266; }","duration":"1.004515749s","start":"2026-07-22T22:53:43.258174Z","end":"2026-07-22T22:53:44.26269Z","steps":["trace[870411220] 'agreement among raft nodes before linearized reading' (duration: 1.004335716s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.262738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.258151Z","time spent":"1.004577931s","remote":"127.0.0.1:33782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-07-22T22:53:44.262732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038928692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-07-22T22:53:44.262676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.012916Z","time spent":"1.249743474s","remote":"127.0.0.1:45254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:53:44.262792Z","caller":"traceutil/trace.go:171","msg":"trace[663467758] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:25266; }","duration":"1.039027874s","start":"2026-07-22T22:53:43.223748Z","end":"2026-07-22T22:53:44.262776Z","steps":["trace[663467758] 'agreement among raft nodes before linearized reading' (duration: 1.03880566s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.263527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.223727Z","time spent":"1.039788069s","remote":"127.0.0.1:33646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-07-22T22:53:44.262877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0240996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-07-22T22:53:44.263629Z","caller":"traceutil/trace.go:171","msg":"trace[26904385] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25266; }","duration":"1.024875915s","start":"2026-07-22T22:53:43.238731Z","end":"2026-07-22T22:53:44.263607Z","steps":["trace[26904385] 'agreement among raft nodes before linearized reading' (duration: 1.024081529s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.263678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.23871Z","time spent":"1.024951407s","remote":"127.0.0.1:33782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-07-22T22:53:44.262897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.660072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-22T22:53:44.262891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.311317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:53:44.263921Z","caller":"traceutil/trace.go:171","msg":"trace[275643506] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25266; }","duration":"396.369678ms","start":"2026-07-22T22:53:43.867519Z","end":"2026-07-22T22:53:44.263888Z","steps":["trace[275643506] 'agreement among raft nodes before linearized reading' (duration: 395.300607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.26401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.8675Z","time spent":"396.48129ms","remote":"127.0.0.1:59376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:53:44.262893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.174777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:44.26407Z","caller":"traceutil/trace.go:171","msg":"trace[1200003281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25266; }","duration":"819.393681ms","start":"2026-07-22T22:53:43.444665Z","end":"2026-07-22T22:53:44.264059Z","steps":["trace[1200003281] 'agreement among raft nodes before linearized reading' (duration: 818.150586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.2641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.444651Z","time spent":"819.438593ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:53:44.262934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.664257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:44.264774Z","caller":"traceutil/trace.go:171","msg":"trace[1278639521] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25266; }","duration":"121.565932ms","start":"2026-07-22T22:53:44.143193Z","end":"2026-07-22T22:53:44.264759Z","steps":["trace[1278639521] 'agreement among raft nodes before linearized reading' (duration: 119.668503ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:44.264807Z","caller":"traceutil/trace.go:171","msg":"trace[785835266] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25266; }","duration":"771.563866ms","start":"2026-07-22T22:53:43.493222Z","end":"2026-07-22T22:53:44.264786Z","steps":["trace[785835266] 'agreement among raft nodes before linearized reading' (duration: 769.685108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.264861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.493198Z","time spent":"771.645898ms","remote":"127.0.0.1:43134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:53:44.262958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.026491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:44.264915Z","caller":"traceutil/trace.go:171","msg":"trace[2129927394] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25266; }","duration":"274.030862ms","start":"2026-07-22T22:53:43.990872Z","end":"2026-07-22T22:53:44.264902Z","steps":["trace[2129927394] 'agreement among raft nodes before linearized reading' (duration: 272.061291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.262974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.216847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-22T22:53:44.265008Z","caller":"traceutil/trace.go:171","msg":"trace[248657176] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25266; }","duration":"108.283189ms","start":"2026-07-22T22:53:44.156703Z","end":"2026-07-22T22:53:44.264986Z","steps":["trace[248657176] 'agreement among raft nodes before linearized reading' (duration: 106.217117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.262971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.862587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-07-22T22:53:44.265091Z","caller":"traceutil/trace.go:171","msg":"trace[1338560678] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25266; }","duration":"883.01294ms","start":"2026-07-22T22:53:43.382068Z","end":"2026-07-22T22:53:44.26508Z","steps":["trace[1338560678] 'agreement among raft nodes before linearized reading' (duration: 880.837136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.265125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.382049Z","time spent":"883.066492ms","remote":"127.0.0.1:33782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-07-22T22:53:44.26298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166913584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-22T22:53:44.26518Z","caller":"traceutil/trace.go:171","msg":"trace[1270090441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25266; }","duration":"1.16915728s","start":"2026-07-22T22:53:43.096011Z","end":"2026-07-22T22:53:44.265168Z","steps":["trace[1270090441] 'agreement among raft nodes before linearized reading' (duration: 1.166832573s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.265217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:43.095992Z","time spent":"1.169214401s","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-22T22:53:44.430046Z","caller":"traceutil/trace.go:171","msg":"trace[500633452] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"161.22256ms","start":"2026-07-22T22:53:44.268794Z","end":"2026-07-22T22:53:44.430016Z","steps":["trace[500633452] 'process raft request' (duration: 140.380114ms)","trace[500633452] 'compare' (duration: 20.610011ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:44.430089Z","caller":"traceutil/trace.go:171","msg":"trace[1894443082] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"161.118038ms","start":"2026-07-22T22:53:44.268942Z","end":"2026-07-22T22:53:44.43006Z","steps":["trace[1894443082] 'process raft request' (duration: 161.011705ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:44.430296Z","caller":"traceutil/trace.go:171","msg":"trace[378582243] linearizableReadLoop","detail":"{readStateIndex:26096; appliedIndex:26092; }","duration":"145.717713ms","start":"2026-07-22T22:53:44.284556Z","end":"2026-07-22T22:53:44.430274Z","steps":["trace[378582243] 'read index received' (duration: 60.838411ms)","trace[378582243] 'applied index is now lower than readState.Index' (duration: 84.877802ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:44.430329Z","caller":"traceutil/trace.go:171","msg":"trace[587744650] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"160.945185ms","start":"2026-07-22T22:53:44.269363Z","end":"2026-07-22T22:53:44.430309Z","steps":["trace[587744650] 'process raft request' (duration: 160.635199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.430603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.010739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-22T22:53:44.430661Z","caller":"traceutil/trace.go:171","msg":"trace[1144814807] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25269; }","duration":"146.136972ms","start":"2026-07-22T22:53:44.284509Z","end":"2026-07-22T22:53:44.430646Z","steps":["trace[1144814807] 'agreement among raft nodes before linearized reading' (duration: 145.895427ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:44.702866Z","caller":"traceutil/trace.go:171","msg":"trace[380261850] linearizableReadLoop","detail":"{readStateIndex:26097; appliedIndex:26096; }","duration":"272.46691ms","start":"2026-07-22T22:53:44.430375Z","end":"2026-07-22T22:53:44.702842Z","steps":["trace[380261850] 'read index received' (duration: 214.760792ms)","trace[380261850] 'applied index is now lower than readState.Index' (duration: 57.705228ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:44.702898Z","caller":"traceutil/trace.go:171","msg":"trace[1146910948] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"356.301261ms","start":"2026-07-22T22:53:44.346554Z","end":"2026-07-22T22:53:44.702855Z","steps":["trace[1146910948] 'process raft request' (duration: 298.570122ms)","trace[1146910948] 'compare' (duration: 57.599626ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:53:44.703056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:44.34653Z","time spent":"356.434264ms","remote":"127.0.0.1:33646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:53:44.703088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.989603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:44.703127Z","caller":"traceutil/trace.go:171","msg":"trace[1391879678] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25270; }","duration":"400.088555ms","start":"2026-07-22T22:53:44.303024Z","end":"2026-07-22T22:53:44.703113Z","steps":["trace[1391879678] 'agreement among raft nodes before linearized reading' (duration: 399.948052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.703163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:44.303006Z","time spent":"400.147876ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:53:44.755764Z","caller":"traceutil/trace.go:171","msg":"trace[564388796] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"319.803996ms","start":"2026-07-22T22:53:44.435937Z","end":"2026-07-22T22:53:44.755741Z","steps":["trace[564388796] 'process raft request' (duration: 319.590472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.755903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:44.435919Z","time spent":"319.919028ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:53:44.756039Z","caller":"traceutil/trace.go:171","msg":"trace[206771211] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"320.062051ms","start":"2026-07-22T22:53:44.43596Z","end":"2026-07-22T22:53:44.756022Z","steps":["trace[206771211] 'process raft request' (duration: 319.706774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.75613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:44.435943Z","time spent":"320.141843ms","remote":"127.0.0.1:59246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2721,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:53:44.756507Z","caller":"traceutil/trace.go:171","msg":"trace[712306388] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"220.710404ms","start":"2026-07-22T22:53:44.535783Z","end":"2026-07-22T22:53:44.756493Z","steps":["trace[712306388] 'process raft request' (duration: 219.943099ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:44.759902Z","caller":"traceutil/trace.go:171","msg":"trace[1382859182] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"101.172704ms","start":"2026-07-22T22:53:44.658693Z","end":"2026-07-22T22:53:44.759865Z","steps":["trace[1382859182] 'process raft request' (duration: 101.094353ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:44.759946Z","caller":"traceutil/trace.go:171","msg":"trace[274307774] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"101.315967ms","start":"2026-07-22T22:53:44.65861Z","end":"2026-07-22T22:53:44.759926Z","steps":["trace[274307774] 'process raft request' (duration: 100.9673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.760149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.876704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:44.760176Z","caller":"traceutil/trace.go:171","msg":"trace[1938985112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25276; }","duration":"309.915704ms","start":"2026-07-22T22:53:44.450252Z","end":"2026-07-22T22:53:44.760168Z","steps":["trace[1938985112] 'agreement among raft nodes before linearized reading' (duration: 309.860663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:44.760198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:53:44.450197Z","time spent":"309.994726ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-22T22:53:51.435764Z","caller":"traceutil/trace.go:171","msg":"trace[5739441] linearizableReadLoop","detail":"{readStateIndex:26228; appliedIndex:26227; }","duration":"113.095728ms","start":"2026-07-22T22:53:51.322643Z","end":"2026-07-22T22:53:51.435739Z","steps":["trace[5739441] 'read index received' (duration: 112.909654ms)","trace[5739441] 'applied index is now lower than readState.Index' (duration: 184.874µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:51.435889Z","caller":"traceutil/trace.go:171","msg":"trace[627292500] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"136.105096ms","start":"2026-07-22T22:53:51.299773Z","end":"2026-07-22T22:53:51.435879Z","steps":["trace[627292500] 'process raft request' (duration: 135.78056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:51.436058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.394724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:53:51.436098Z","caller":"traceutil/trace.go:171","msg":"trace[1501454644] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25400; }","duration":"113.482435ms","start":"2026-07-22T22:53:51.322606Z","end":"2026-07-22T22:53:51.436089Z","steps":["trace[1501454644] 'agreement among raft nodes before linearized reading' (duration: 113.402373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:51.65078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.332105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:53:51.650867Z","caller":"traceutil/trace.go:171","msg":"trace[1605825684] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"199.849918ms","start":"2026-07-22T22:53:51.451001Z","end":"2026-07-22T22:53:51.650851Z","steps":["trace[1605825684] 'process raft request' (duration: 82.36036ms)","trace[1605825684] 'compare' (duration: 117.227133ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:51.652114Z","caller":"traceutil/trace.go:171","msg":"trace[64262425] linearizableReadLoop","detail":"{readStateIndex:26232; appliedIndex:26228; }","duration":"180.539425ms","start":"2026-07-22T22:53:51.471556Z","end":"2026-07-22T22:53:51.652095Z","steps":["trace[64262425] 'read index received' (duration: 61.906173ms)","trace[64262425] 'applied index is now lower than readState.Index' (duration: 118.632362ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:53:51.65229Z","caller":"traceutil/trace.go:171","msg":"trace[1961318229] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"200.936741ms","start":"2026-07-22T22:53:51.451343Z","end":"2026-07-22T22:53:51.65228Z","steps":["trace[1961318229] 'process raft request' (duration: 200.595204ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:51.652499Z","caller":"traceutil/trace.go:171","msg":"trace[1524421971] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"200.988112ms","start":"2026-07-22T22:53:51.451505Z","end":"2026-07-22T22:53:51.652493Z","steps":["trace[1524421971] 'process raft request' (duration: 200.514812ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:53:51.652632Z","caller":"traceutil/trace.go:171","msg":"trace[2132640833] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"200.809648ms","start":"2026-07-22T22:53:51.451814Z","end":"2026-07-22T22:53:51.652624Z","steps":["trace[2132640833] 'process raft request' (duration: 200.255067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:53:51.6528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.242359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-22T22:53:51.652829Z","caller":"traceutil/trace.go:171","msg":"trace[1639730010] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25404; }","duration":"181.31441ms","start":"2026-07-22T22:53:51.471509Z","end":"2026-07-22T22:53:51.652823Z","steps":["trace[1639730010] 'agreement among raft nodes before linearized reading' (duration: 181.207718ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:22.612702Z","caller":"traceutil/trace.go:171","msg":"trace[249856061] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"229.3104ms","start":"2026-07-22T22:54:22.383364Z","end":"2026-07-22T22:54:22.612674Z","steps":["trace[249856061] 'process raft request' (duration: 229.159696ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:22.835308Z","caller":"traceutil/trace.go:171","msg":"trace[881802477] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"451.252569ms","start":"2026-07-22T22:54:22.38403Z","end":"2026-07-22T22:54:22.835283Z","steps":["trace[881802477] 'process raft request' (duration: 451.032785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:22.835461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:22.384004Z","time spent":"451.379641ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:54:22.835543Z","caller":"traceutil/trace.go:171","msg":"trace[1706894038] linearizableReadLoop","detail":"{readStateIndex:26481; appliedIndex:26478; }","duration":"393.00181ms","start":"2026-07-22T22:54:22.442499Z","end":"2026-07-22T22:54:22.835501Z","steps":["trace[1706894038] 'read index received' (duration: 170.070031ms)","trace[1706894038] 'applied index is now lower than readState.Index' (duration: 222.929039ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:22.835602Z","caller":"traceutil/trace.go:171","msg":"trace[870481563] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"449.8336ms","start":"2026-07-22T22:54:22.385719Z","end":"2026-07-22T22:54:22.835553Z","steps":["trace[870481563] 'process raft request' (duration: 449.522243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:22.835711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.178544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:22.835781Z","caller":"traceutil/trace.go:171","msg":"trace[2107940053] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25647; }","duration":"393.298826ms","start":"2026-07-22T22:54:22.442466Z","end":"2026-07-22T22:54:22.835764Z","steps":["trace[2107940053] 'agreement among raft nodes before linearized reading' (duration: 393.154503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:22.835782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:22.385713Z","time spent":"449.976812ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:54:22.835819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:22.442447Z","time spent":"393.363978ms","remote":"127.0.0.1:43148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:54:22.835798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.99839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-22T22:54:22.835837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.235388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:22.835892Z","caller":"traceutil/trace.go:171","msg":"trace[33481167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25647; }","duration":"392.151113ms","start":"2026-07-22T22:54:22.443723Z","end":"2026-07-22T22:54:22.835874Z","steps":["trace[33481167] 'agreement among raft nodes before linearized reading' (duration: 392.00434ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:22.835898Z","caller":"traceutil/trace.go:171","msg":"trace[1850756232] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:25647; }","duration":"181.321451ms","start":"2026-07-22T22:54:22.654557Z","end":"2026-07-22T22:54:22.835878Z","steps":["trace[1850756232] 'agreement among raft nodes before linearized reading' (duration: 181.185318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:22.835938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:22.4437Z","time spent":"392.229585ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:54:22.835994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.508617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-22T22:54:22.83605Z","caller":"traceutil/trace.go:171","msg":"trace[260513526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25647; }","duration":"239.601079ms","start":"2026-07-22T22:54:22.596434Z","end":"2026-07-22T22:54:22.836035Z","steps":["trace[260513526] 'agreement among raft nodes before linearized reading' (duration: 239.428136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:22.836124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.225455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:22.836148Z","caller":"traceutil/trace.go:171","msg":"trace[1846961890] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25647; }","duration":"124.289516ms","start":"2026-07-22T22:54:22.711853Z","end":"2026-07-22T22:54:22.836143Z","steps":["trace[1846961890] 'agreement among raft nodes before linearized reading' (duration: 124.248116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:22.836203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.07229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:22.836251Z","caller":"traceutil/trace.go:171","msg":"trace[1567570406] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25647; }","duration":"318.191813ms","start":"2026-07-22T22:54:22.518044Z","end":"2026-07-22T22:54:22.836236Z","steps":["trace[1567570406] 'agreement among raft nodes before linearized reading' (duration: 318.076071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:22.836381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:22.518029Z","time spent":"318.244354ms","remote":"127.0.0.1:54760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:54:22.96305Z","caller":"traceutil/trace.go:171","msg":"trace[1850768746] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"122.47464ms","start":"2026-07-22T22:54:22.840541Z","end":"2026-07-22T22:54:22.963016Z","steps":["trace[1850768746] 'process raft request' (duration: 53.500092ms)","trace[1850768746] 'compare' (duration: 68.825355ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:23.039612Z","caller":"traceutil/trace.go:171","msg":"trace[422708717] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"124.167983ms","start":"2026-07-22T22:54:22.915418Z","end":"2026-07-22T22:54:23.039586Z","steps":["trace[422708717] 'process raft request' (duration: 124.027661ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:23.112825Z","caller":"traceutil/trace.go:171","msg":"trace[1966830416] linearizableReadLoop","detail":"{readStateIndex:26485; appliedIndex:26483; }","duration":"149.844927ms","start":"2026-07-22T22:54:22.962966Z","end":"2026-07-22T22:54:23.112811Z","steps":["trace[1966830416] 'read index received' (duration: 76.635124ms)","trace[1966830416] 'applied index is now lower than readState.Index' (duration: 73.209293ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:23.112882Z","caller":"traceutil/trace.go:171","msg":"trace[1809929656] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"196.50882ms","start":"2026-07-22T22:54:22.916356Z","end":"2026-07-22T22:54:23.112865Z","steps":["trace[1809929656] 'process raft request' (duration: 196.419219ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:23.11288Z","caller":"traceutil/trace.go:171","msg":"trace[1871764447] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"196.591272ms","start":"2026-07-22T22:54:22.916259Z","end":"2026-07-22T22:54:23.112851Z","steps":["trace[1871764447] 'process raft request' (duration: 196.397918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:23.112969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.6191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-22T22:54:23.113006Z","caller":"traceutil/trace.go:171","msg":"trace[1926228286] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25651; }","duration":"190.685611ms","start":"2026-07-22T22:54:22.922309Z","end":"2026-07-22T22:54:23.112995Z","steps":["trace[1926228286] 'agreement among raft nodes before linearized reading' (duration: 190.568299ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:23.353452Z","caller":"traceutil/trace.go:171","msg":"trace[2020593043] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"177.993953ms","start":"2026-07-22T22:54:23.175429Z","end":"2026-07-22T22:54:23.353423Z","steps":["trace[2020593043] 'process raft request' (duration: 113.278112ms)","trace[2020593043] 'compare' (duration: 64.386304ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:23.353742Z","caller":"traceutil/trace.go:171","msg":"trace[2064841180] linearizableReadLoop","detail":"{readStateIndex:26490; appliedIndex:26487; }","duration":"124.184364ms","start":"2026-07-22T22:54:23.229528Z","end":"2026-07-22T22:54:23.353712Z","steps":["trace[2064841180] 'read index received' (duration: 59.109496ms)","trace[2064841180] 'applied index is now lower than readState.Index' (duration: 65.073577ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:23.353802Z","caller":"traceutil/trace.go:171","msg":"trace[1828958953] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"178.222048ms","start":"2026-07-22T22:54:23.175556Z","end":"2026-07-22T22:54:23.353778Z","steps":["trace[1828958953] 'process raft request' (duration: 178.015644ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:23.353921Z","caller":"traceutil/trace.go:171","msg":"trace[602910121] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"178.37993ms","start":"2026-07-22T22:54:23.175522Z","end":"2026-07-22T22:54:23.353902Z","steps":["trace[602910121] 'process raft request' (duration: 177.85619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:23.355021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.467521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-22T22:54:23.355198Z","caller":"traceutil/trace.go:171","msg":"trace[298285673] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:25656; }","duration":"125.691055ms","start":"2026-07-22T22:54:23.229488Z","end":"2026-07-22T22:54:23.355179Z","steps":["trace[298285673] 'agreement among raft nodes before linearized reading' (duration: 124.329197ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:23.782236Z","caller":"traceutil/trace.go:171","msg":"trace[821947184] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"278.048434ms","start":"2026-07-22T22:54:23.504165Z","end":"2026-07-22T22:54:23.782214Z","steps":["trace[821947184] 'process raft request' (duration: 242.166791ms)","trace[821947184] 'compare' (duration: 35.705969ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:23.783299Z","caller":"traceutil/trace.go:171","msg":"trace[777799481] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"214.287842ms","start":"2026-07-22T22:54:23.568986Z","end":"2026-07-22T22:54:23.783274Z","steps":["trace[777799481] 'process raft request' (duration: 214.147269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:24.120174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.555318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-22T22:54:24.120194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.572775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-22T22:54:24.120246Z","caller":"traceutil/trace.go:171","msg":"trace[113084223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25658; }","duration":"212.68762ms","start":"2026-07-22T22:54:23.907543Z","end":"2026-07-22T22:54:24.12023Z","steps":["trace[113084223] 'range keys from in-memory index tree' (duration: 212.361924ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:24.120279Z","caller":"traceutil/trace.go:171","msg":"trace[82949575] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25658; }","duration":"203.678547ms","start":"2026-07-22T22:54:23.916579Z","end":"2026-07-22T22:54:24.120257Z","steps":["trace[82949575] 'range keys from in-memory index tree' (duration: 203.3294ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:24.548746Z","caller":"traceutil/trace.go:171","msg":"trace[140949934] linearizableReadLoop","detail":"{readStateIndex:26495; appliedIndex:26494; }","duration":"185.453616ms","start":"2026-07-22T22:54:24.363266Z","end":"2026-07-22T22:54:24.54872Z","steps":["trace[140949934] 'read index received' (duration: 133.153018ms)","trace[140949934] 'applied index is now lower than readState.Index' (duration: 52.299458ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:54:24.549015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.641459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-07-22T22:54:24.549048Z","caller":"traceutil/trace.go:171","msg":"trace[2128456772] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25660; }","duration":"185.69295ms","start":"2026-07-22T22:54:24.363345Z","end":"2026-07-22T22:54:24.549038Z","steps":["trace[2128456772] 'agreement among raft nodes before linearized reading' (duration: 185.511257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:24.549025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.310879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-22T22:54:24.549043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.768522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-07-22T22:54:24.549102Z","caller":"traceutil/trace.go:171","msg":"trace[1038768655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25660; }","duration":"105.430832ms","start":"2026-07-22T22:54:24.443654Z","end":"2026-07-22T22:54:24.549085Z","steps":["trace[1038768655] 'agreement among raft nodes before linearized reading' (duration: 105.31313ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:24.549107Z","caller":"traceutil/trace.go:171","msg":"trace[1064485412] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25660; }","duration":"185.870823ms","start":"2026-07-22T22:54:24.363221Z","end":"2026-07-22T22:54:24.549092Z","steps":["trace[1064485412] 'agreement among raft nodes before linearized reading' (duration: 185.625428ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:24.549011Z","caller":"traceutil/trace.go:171","msg":"trace[814734183] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"236.730831ms","start":"2026-07-22T22:54:24.312223Z","end":"2026-07-22T22:54:24.548954Z","steps":["trace[814734183] 'process raft request' (duration: 184.198159ms)","trace[814734183] 'compare' (duration: 51.917269ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:54:24.549311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.823547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-07-22T22:54:24.54949Z","caller":"traceutil/trace.go:171","msg":"trace[1871632371] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25660; }","duration":"173.039691ms","start":"2026-07-22T22:54:24.376441Z","end":"2026-07-22T22:54:24.549481Z","steps":["trace[1871632371] 'agreement among raft nodes before linearized reading' (duration: 172.817137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.131106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.011366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:54:25.131208Z","caller":"traceutil/trace.go:171","msg":"trace[259242392] linearizableReadLoop","detail":"{readStateIndex:26498; appliedIndex:26497; }","duration":"164.343405ms","start":"2026-07-22T22:54:24.966848Z","end":"2026-07-22T22:54:25.131191Z","steps":["trace[259242392] 'read index received' (duration: 51.143034ms)","trace[259242392] 'applied index is now lower than readState.Index' (duration: 113.198941ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:25.131254Z","caller":"traceutil/trace.go:171","msg":"trace[1042597668] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"288.914507ms","start":"2026-07-22T22:54:24.842312Z","end":"2026-07-22T22:54:25.131226Z","steps":["trace[1042597668] 'process raft request' (duration: 175.694516ms)","trace[1042597668] 'compare' (duration: 112.615488ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:54:25.131383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.546638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-22T22:54:25.131425Z","caller":"traceutil/trace.go:171","msg":"trace[798245668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25663; }","duration":"164.63688ms","start":"2026-07-22T22:54:24.966778Z","end":"2026-07-22T22:54:25.131415Z","steps":["trace[798245668] 'agreement among raft nodes before linearized reading' (duration: 164.463487ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:25.147402Z","caller":"traceutil/trace.go:171","msg":"trace[1920765999] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"102.375449ms","start":"2026-07-22T22:54:25.044997Z","end":"2026-07-22T22:54:25.147372Z","steps":["trace[1920765999] 'process raft request' (duration: 102.198445ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:25.533514Z","caller":"traceutil/trace.go:171","msg":"trace[1030820306] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"354.113416ms","start":"2026-07-22T22:54:25.179364Z","end":"2026-07-22T22:54:25.533477Z","steps":["trace[1030820306] 'process raft request' (duration: 353.902482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.533673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:25.179342Z","time spent":"354.250208ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:54:25.53369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.3251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-22T22:54:25.53373Z","caller":"traceutil/trace.go:171","msg":"trace[46714336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25668; }","duration":"329.399571ms","start":"2026-07-22T22:54:25.204321Z","end":"2026-07-22T22:54:25.53372Z","steps":["trace[46714336] 'agreement among raft nodes before linearized reading' (duration: 329.234998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.533764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:25.204307Z","time spent":"329.450943ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-22T22:54:25.53349Z","caller":"traceutil/trace.go:171","msg":"trace[514998864] linearizableReadLoop","detail":"{readStateIndex:26503; appliedIndex:26502; }","duration":"329.084165ms","start":"2026-07-22T22:54:25.204352Z","end":"2026-07-22T22:54:25.533437Z","steps":["trace[514998864] 'read index received' (duration: 328.957113ms)","trace[514998864] 'applied index is now lower than readState.Index' (duration: 126.002µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:25.603766Z","caller":"traceutil/trace.go:171","msg":"trace[64006848] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"377.79868ms","start":"2026-07-22T22:54:25.225946Z","end":"2026-07-22T22:54:25.603745Z","steps":["trace[64006848] 'process raft request' (duration: 377.682348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.603864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.423954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:25.603914Z","caller":"traceutil/trace.go:171","msg":"trace[951084139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25669; }","duration":"162.512216ms","start":"2026-07-22T22:54:25.441391Z","end":"2026-07-22T22:54:25.603903Z","steps":["trace[951084139] 'agreement among raft nodes before linearized reading' (duration: 162.422985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.603933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:25.225927Z","time spent":"377.896692ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:54:25.604153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.993729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-22T22:54:25.604187Z","caller":"traceutil/trace.go:171","msg":"trace[1037545543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25669; }","duration":"246.065541ms","start":"2026-07-22T22:54:25.358112Z","end":"2026-07-22T22:54:25.604177Z","steps":["trace[1037545543] 'agreement among raft nodes before linearized reading' (duration: 245.974379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.604194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.785566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:25.604215Z","caller":"traceutil/trace.go:171","msg":"trace[2084654325] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25669; }","duration":"271.836478ms","start":"2026-07-22T22:54:25.332371Z","end":"2026-07-22T22:54:25.604208Z","steps":["trace[2084654325] 'agreement among raft nodes before linearized reading' (duration: 271.794407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.604274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.708232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:25.604297Z","caller":"traceutil/trace.go:171","msg":"trace[1969071137] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25669; }","duration":"239.749902ms","start":"2026-07-22T22:54:25.364537Z","end":"2026-07-22T22:54:25.604287Z","steps":["trace[1969071137] 'agreement among raft nodes before linearized reading' (duration: 239.707351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.604378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.658373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-22T22:54:25.604432Z","caller":"traceutil/trace.go:171","msg":"trace[1046397623] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25669; }","duration":"245.748665ms","start":"2026-07-22T22:54:25.358667Z","end":"2026-07-22T22:54:25.604416Z","steps":["trace[1046397623] 'agreement among raft nodes before linearized reading' (duration: 245.585431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.604484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.395028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-22T22:54:25.604506Z","caller":"traceutil/trace.go:171","msg":"trace[369620101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25669; }","duration":"246.451009ms","start":"2026-07-22T22:54:25.358048Z","end":"2026-07-22T22:54:25.604499Z","steps":["trace[369620101] 'agreement among raft nodes before linearized reading' (duration: 246.03371ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:25.720864Z","caller":"traceutil/trace.go:171","msg":"trace[1347217368] linearizableReadLoop","detail":"{readStateIndex:26505; appliedIndex:26504; }","duration":"116.768563ms","start":"2026-07-22T22:54:25.604068Z","end":"2026-07-22T22:54:25.720837Z","steps":["trace[1347217368] 'read index received' (duration: 113.119079ms)","trace[1347217368] 'applied index is now lower than readState.Index' (duration: 3.648544ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:25.720953Z","caller":"traceutil/trace.go:171","msg":"trace[795178318] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"182.853111ms","start":"2026-07-22T22:54:25.53807Z","end":"2026-07-22T22:54:25.720924Z","steps":["trace[795178318] 'process raft request' (duration: 179.110534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.721047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.956539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:54:25.721169Z","caller":"traceutil/trace.go:171","msg":"trace[732286538] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25670; }","duration":"150.125333ms","start":"2026-07-22T22:54:25.571016Z","end":"2026-07-22T22:54:25.721141Z","steps":["trace[732286538] 'agreement among raft nodes before linearized reading' (duration: 149.929899ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:25.81669Z","caller":"traceutil/trace.go:171","msg":"trace[791840022] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"209.187288ms","start":"2026-07-22T22:54:25.607474Z","end":"2026-07-22T22:54:25.816661Z","steps":["trace[791840022] 'process raft request' (duration: 208.964594ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:25.816987Z","caller":"traceutil/trace.go:171","msg":"trace[61149437] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"209.128817ms","start":"2026-07-22T22:54:25.607828Z","end":"2026-07-22T22:54:25.816957Z","steps":["trace[61149437] 'process raft request' (duration: 208.78699ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:25.817389Z","caller":"traceutil/trace.go:171","msg":"trace[1554107080] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"209.452884ms","start":"2026-07-22T22:54:25.607896Z","end":"2026-07-22T22:54:25.817349Z","steps":["trace[1554107080] 'process raft request' (duration: 208.951294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:25.81744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.241961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:25.817949Z","caller":"traceutil/trace.go:171","msg":"trace[1105943439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25673; }","duration":"212.346414ms","start":"2026-07-22T22:54:25.605148Z","end":"2026-07-22T22:54:25.817494Z","steps":["trace[1105943439] 'agreement among raft nodes before linearized reading' (duration: 212.241472ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:26.342804Z","caller":"traceutil/trace.go:171","msg":"trace[1479480722] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"112.380583ms","start":"2026-07-22T22:54:26.230386Z","end":"2026-07-22T22:54:26.342767Z","steps":["trace[1479480722] 'process raft request' (duration: 111.967904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:26.59426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.700836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:26.594326Z","caller":"traceutil/trace.go:171","msg":"trace[333583616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25674; }","duration":"149.779107ms","start":"2026-07-22T22:54:26.44453Z","end":"2026-07-22T22:54:26.594309Z","steps":["trace[333583616] 'range keys from in-memory index tree' (duration: 149.643975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:26.971417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.596332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:54:26.971564Z","caller":"traceutil/trace.go:171","msg":"trace[209751837] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25675; }","duration":"146.816337ms","start":"2026-07-22T22:54:26.824726Z","end":"2026-07-22T22:54:26.971543Z","steps":["trace[209751837] 'count revisions from in-memory index tree' (duration: 146.346336ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:27.064547Z","caller":"traceutil/trace.go:171","msg":"trace[1404904971] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"141.543519ms","start":"2026-07-22T22:54:26.922983Z","end":"2026-07-22T22:54:27.064526Z","steps":["trace[1404904971] 'process raft request' (duration: 139.273813ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:27.290947Z","caller":"traceutil/trace.go:171","msg":"trace[1880680502] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"155.967163ms","start":"2026-07-22T22:54:27.134946Z","end":"2026-07-22T22:54:27.290913Z","steps":["trace[1880680502] 'process raft request' (duration: 83.79932ms)","trace[1880680502] 'compare' (duration: 71.964048ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:54:27.546982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.916241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:54:27.547816Z","caller":"traceutil/trace.go:171","msg":"trace[796029845] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"395.677274ms","start":"2026-07-22T22:54:27.152101Z","end":"2026-07-22T22:54:27.547778Z","steps":["trace[796029845] 'process raft request' (duration: 287.877435ms)","trace[796029845] 'compare' (duration: 106.786339ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:54:27.54801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:27.152076Z","time spent":"395.832187ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:54:27.562847Z","caller":"traceutil/trace.go:171","msg":"trace[2020844498] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"393.49838ms","start":"2026-07-22T22:54:27.16933Z","end":"2026-07-22T22:54:27.562829Z","steps":["trace[2020844498] 'process raft request' (duration: 377.891181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:27.562972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:27.169319Z","time spent":"393.588833ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:54:27.56353Z","caller":"traceutil/trace.go:171","msg":"trace[167359710] linearizableReadLoop","detail":"{readStateIndex:26513; appliedIndex:26511; }","duration":"395.818327ms","start":"2026-07-22T22:54:27.167692Z","end":"2026-07-22T22:54:27.56351Z","steps":["trace[167359710] 'read index received' (duration: 51.114312ms)","trace[167359710] 'applied index is now lower than readState.Index' (duration: 344.703015ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:54:27.563852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.143259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-22T22:54:27.56391Z","caller":"traceutil/trace.go:171","msg":"trace[1626476854] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25680; }","duration":"237.238961ms","start":"2026-07-22T22:54:27.326654Z","end":"2026-07-22T22:54:27.563893Z","steps":["trace[1626476854] 'agreement among raft nodes before linearized reading' (duration: 237.063688ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:27.564095Z","caller":"traceutil/trace.go:171","msg":"trace[363155699] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"394.669384ms","start":"2026-07-22T22:54:27.169411Z","end":"2026-07-22T22:54:27.564081Z","steps":["trace[363155699] 'process raft request' (duration: 393.343858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:27.564174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:27.169384Z","time spent":"394.749595ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:54:27.564312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.619413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-22T22:54:27.564382Z","caller":"traceutil/trace.go:171","msg":"trace[1319995697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25680; }","duration":"396.724785ms","start":"2026-07-22T22:54:27.167646Z","end":"2026-07-22T22:54:27.564371Z","steps":["trace[1319995697] 'agreement among raft nodes before linearized reading' (duration: 396.602013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:27.564424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:27.167624Z","time spent":"396.790438ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:54:27.564414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.368567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:27.564623Z","caller":"traceutil/trace.go:171","msg":"trace[1331861813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25680; }","duration":"120.581851ms","start":"2026-07-22T22:54:27.44403Z","end":"2026-07-22T22:54:27.564612Z","steps":["trace[1331861813] 'agreement among raft nodes before linearized reading' (duration: 120.347846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:27.892519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.178154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:54:27.892678Z","caller":"traceutil/trace.go:171","msg":"trace[1232580414] linearizableReadLoop","detail":"{readStateIndex:26517; appliedIndex:26515; }","duration":"322.9307ms","start":"2026-07-22T22:54:27.569731Z","end":"2026-07-22T22:54:27.892662Z","steps":["trace[1232580414] 'read index received' (duration: 173.50056ms)","trace[1232580414] 'applied index is now lower than readState.Index' (duration: 149.429229ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:27.892921Z","caller":"traceutil/trace.go:171","msg":"trace[979573222] transaction","detail":"{read_only:false; response_revision:25681; number_of_response:1; }","duration":"323.427619ms","start":"2026-07-22T22:54:27.569479Z","end":"2026-07-22T22:54:27.892907Z","steps":["trace[979573222] 'process raft request' (duration: 173.783276ms)","trace[979573222] 'compare' (duration: 148.776326ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:54:27.892981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:27.569464Z","time spent":"323.487941ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:54:27.893132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.166455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-22T22:54:27.894097Z","caller":"traceutil/trace.go:171","msg":"trace[1682306111] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25682; }","duration":"278.133056ms","start":"2026-07-22T22:54:27.615932Z","end":"2026-07-22T22:54:27.894065Z","steps":["trace[1682306111] 'agreement among raft nodes before linearized reading' (duration: 277.157135ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:27.893166Z","caller":"traceutil/trace.go:171","msg":"trace[151764306] transaction","detail":"{read_only:false; response_revision:25682; number_of_response:1; }","duration":"323.489121ms","start":"2026-07-22T22:54:27.569668Z","end":"2026-07-22T22:54:27.893157Z","steps":["trace[151764306] 'process raft request' (duration: 322.944291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:27.894248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:27.569639Z","time spent":"324.544573ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:54:27.893268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.533103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17940"} {"level":"info","ts":"2026-07-22T22:54:27.894345Z","caller":"traceutil/trace.go:171","msg":"trace[1581291993] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25682; }","duration":"324.617435ms","start":"2026-07-22T22:54:27.569709Z","end":"2026-07-22T22:54:27.894326Z","steps":["trace[1581291993] 'agreement among raft nodes before linearized reading' (duration: 323.473592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:27.893511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.814776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-22T22:54:27.894401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:54:27.569701Z","time spent":"324.680456ms","remote":"127.0.0.1:33788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":17963,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-22T22:54:27.894411Z","caller":"traceutil/trace.go:171","msg":"trace[887255160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25682; }","duration":"169.756725ms","start":"2026-07-22T22:54:27.724642Z","end":"2026-07-22T22:54:27.894399Z","steps":["trace[887255160] 'agreement among raft nodes before linearized reading' (duration: 168.808215ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:28.154526Z","caller":"traceutil/trace.go:171","msg":"trace[792614] linearizableReadLoop","detail":"{readStateIndex:26521; appliedIndex:26517; }","duration":"196.04671ms","start":"2026-07-22T22:54:27.958456Z","end":"2026-07-22T22:54:28.154503Z","steps":["trace[792614] 'read index received' (duration: 19.473727ms)","trace[792614] 'applied index is now lower than readState.Index' (duration: 176.572103ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:28.154678Z","caller":"traceutil/trace.go:171","msg":"trace[875315562] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"257.220439ms","start":"2026-07-22T22:54:27.897444Z","end":"2026-07-22T22:54:28.154664Z","steps":["trace[875315562] 'process raft request' (duration: 256.969443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:28.15473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.258764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:28.15478Z","caller":"traceutil/trace.go:171","msg":"trace[1726979349] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25686; }","duration":"196.347607ms","start":"2026-07-22T22:54:27.958418Z","end":"2026-07-22T22:54:28.154765Z","steps":["trace[1726979349] 'agreement among raft nodes before linearized reading' (duration: 196.225064ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:28.154784Z","caller":"traceutil/trace.go:171","msg":"trace[108706655] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"257.153848ms","start":"2026-07-22T22:54:27.897584Z","end":"2026-07-22T22:54:28.154738Z","steps":["trace[108706655] 'process raft request' (duration: 256.874152ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:28.154956Z","caller":"traceutil/trace.go:171","msg":"trace[1421476194] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"257.188319ms","start":"2026-07-22T22:54:27.897429Z","end":"2026-07-22T22:54:28.154618Z","steps":["trace[1421476194] 'process raft request' (duration: 256.835102ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:28.490029Z","caller":"traceutil/trace.go:171","msg":"trace[2084221897] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"140.175271ms","start":"2026-07-22T22:54:28.349838Z","end":"2026-07-22T22:54:28.490014Z","steps":["trace[2084221897] 'process raft request' (duration: 140.056248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:28.691872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.180177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:28.691942Z","caller":"traceutil/trace.go:171","msg":"trace[1090024546] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25687; }","duration":"106.302999ms","start":"2026-07-22T22:54:28.585621Z","end":"2026-07-22T22:54:28.691924Z","steps":["trace[1090024546] 'count revisions from in-memory index tree' (duration: 106.060805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:28.834248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.76089ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-22T22:54:29.198168Z","caller":"traceutil/trace.go:171","msg":"trace[1220887903] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"128.818809ms","start":"2026-07-22T22:54:29.069326Z","end":"2026-07-22T22:54:29.198145Z","steps":["trace[1220887903] 'process raft request' (duration: 128.648655ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:29.42736Z","caller":"traceutil/trace.go:171","msg":"trace[1225233604] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"130.666287ms","start":"2026-07-22T22:54:29.296664Z","end":"2026-07-22T22:54:29.42733Z","steps":["trace[1225233604] 'process raft request' (duration: 130.482983ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:30.786104Z","caller":"traceutil/trace.go:171","msg":"trace[771187302] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"119.856906ms","start":"2026-07-22T22:54:30.666216Z","end":"2026-07-22T22:54:30.786073Z","steps":["trace[771187302] 'process raft request' (duration: 119.655272ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:32.112847Z","caller":"traceutil/trace.go:171","msg":"trace[647452287] linearizableReadLoop","detail":"{readStateIndex:26549; appliedIndex:26548; }","duration":"120.609612ms","start":"2026-07-22T22:54:31.992212Z","end":"2026-07-22T22:54:32.112822Z","steps":["trace[647452287] 'read index received' (duration: 68.059899ms)","trace[647452287] 'applied index is now lower than readState.Index' (duration: 52.548643ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:32.11311Z","caller":"traceutil/trace.go:171","msg":"trace[1082333269] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"200.488832ms","start":"2026-07-22T22:54:31.912589Z","end":"2026-07-22T22:54:32.113078Z","steps":["trace[1082333269] 'process raft request' (duration: 147.610912ms)","trace[1082333269] 'compare' (duration: 52.511192ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:54:32.113167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.934388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-22T22:54:32.113236Z","caller":"traceutil/trace.go:171","msg":"trace[1922974716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25713; }","duration":"121.03934ms","start":"2026-07-22T22:54:31.99218Z","end":"2026-07-22T22:54:32.113219Z","steps":["trace[1922974716] 'agreement among raft nodes before linearized reading' (duration: 120.833445ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:59.418765Z","caller":"traceutil/trace.go:171","msg":"trace[1784145074] transaction","detail":"{read_only:false; response_revision:26064; number_of_response:1; }","duration":"109.475643ms","start":"2026-07-22T22:54:59.309259Z","end":"2026-07-22T22:54:59.418734Z","steps":["trace[1784145074] 'process raft request' (duration: 109.070135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:54:59.805285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.501882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-22T22:54:59.80542Z","caller":"traceutil/trace.go:171","msg":"trace[417637792] transaction","detail":"{read_only:false; response_revision:26067; number_of_response:1; }","duration":"184.424624ms","start":"2026-07-22T22:54:59.620965Z","end":"2026-07-22T22:54:59.805389Z","steps":["trace[417637792] 'process raft request' (duration: 56.721489ms)","trace[417637792] 'compare' (duration: 127.203835ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:54:59.820521Z","caller":"traceutil/trace.go:171","msg":"trace[142520998] transaction","detail":"{read_only:false; response_revision:26068; number_of_response:1; }","duration":"136.110018ms","start":"2026-07-22T22:54:59.684386Z","end":"2026-07-22T22:54:59.820496Z","steps":["trace[142520998] 'process raft request' (duration: 135.948674ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:59.862245Z","caller":"traceutil/trace.go:171","msg":"trace[1385220040] transaction","detail":"{read_only:false; response_revision:26069; number_of_response:1; }","duration":"176.8748ms","start":"2026-07-22T22:54:59.685346Z","end":"2026-07-22T22:54:59.86222Z","steps":["trace[1385220040] 'process raft request' (duration: 176.732117ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:59.862367Z","caller":"traceutil/trace.go:171","msg":"trace[686654975] linearizableReadLoop","detail":"{readStateIndex:26914; appliedIndex:26911; }","duration":"105.866259ms","start":"2026-07-22T22:54:59.756484Z","end":"2026-07-22T22:54:59.86235Z","steps":["trace[686654975] 'read index received' (duration: 63.916944ms)","trace[686654975] 'applied index is now lower than readState.Index' (duration: 41.947195ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:54:59.862474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.969523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:54:59.862503Z","caller":"traceutil/trace.go:171","msg":"trace[193280973] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:26070; }","duration":"106.061524ms","start":"2026-07-22T22:54:59.756434Z","end":"2026-07-22T22:54:59.862495Z","steps":["trace[193280973] 'agreement among raft nodes before linearized reading' (duration: 105.968612ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:54:59.862446Z","caller":"traceutil/trace.go:171","msg":"trace[812001684] transaction","detail":"{read_only:false; response_revision:26070; number_of_response:1; }","duration":"176.873191ms","start":"2026-07-22T22:54:59.685548Z","end":"2026-07-22T22:54:59.862421Z","steps":["trace[812001684] 'process raft request' (duration: 176.638395ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:55:40.280138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23438} {"level":"info","ts":"2026-07-22T22:55:40.412658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23438,"took":"128.324879ms","hash":1246694905,"current-db-size-bytes":60989440,"current-db-size":"61 MB","current-db-size-in-use-bytes":39227392,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-07-22T22:55:40.412731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1246694905,"revision":23438,"compact-revision":20477} {"level":"info","ts":"2026-07-22T22:56:16.249934Z","caller":"traceutil/trace.go:171","msg":"trace[771844131] transaction","detail":"{read_only:false; response_revision:26831; number_of_response:1; }","duration":"131.981393ms","start":"2026-07-22T22:56:16.117931Z","end":"2026-07-22T22:56:16.249913Z","steps":["trace[771844131] 'process raft request' (duration: 131.947862ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:56:16.250345Z","caller":"traceutil/trace.go:171","msg":"trace[1818227258] transaction","detail":"{read_only:false; response_revision:26830; number_of_response:1; }","duration":"135.966415ms","start":"2026-07-22T22:56:16.114347Z","end":"2026-07-22T22:56:16.250314Z","steps":["trace[1818227258] 'process raft request' (duration: 135.394242ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:57:46.28602Z","caller":"traceutil/trace.go:171","msg":"trace[433689324] linearizableReadLoop","detail":"{readStateIndex:28661; appliedIndex:28659; }","duration":"111.112518ms","start":"2026-07-22T22:57:46.174879Z","end":"2026-07-22T22:57:46.285992Z","steps":["trace[433689324] 'read index received' (duration: 16.004587ms)","trace[433689324] 'applied index is now lower than readState.Index' (duration: 95.106761ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:57:46.286141Z","caller":"traceutil/trace.go:171","msg":"trace[1054667171] transaction","detail":"{read_only:false; response_revision:27774; number_of_response:1; }","duration":"111.473885ms","start":"2026-07-22T22:57:46.174648Z","end":"2026-07-22T22:57:46.286122Z","steps":["trace[1054667171] 'process raft request' (duration: 106.33729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:57:46.286296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.402813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/openstack-exporter\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-22T22:57:46.286385Z","caller":"traceutil/trace.go:171","msg":"trace[1567320456] range","detail":"{range_begin:/registry/services/specs/openstack/openstack-exporter; range_end:; response_count:0; response_revision:27774; }","duration":"111.525916ms","start":"2026-07-22T22:57:46.174838Z","end":"2026-07-22T22:57:46.286364Z","steps":["trace[1567320456] 'agreement among raft nodes before linearized reading' (duration: 111.322112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:57:46.286387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.786178ms","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:6"} {"level":"info","ts":"2026-07-22T22:57:46.286496Z","caller":"traceutil/trace.go:171","msg":"trace[981797929] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:27774; }","duration":"101.955461ms","start":"2026-07-22T22:57:46.184517Z","end":"2026-07-22T22:57:46.286472Z","steps":["trace[981797929] 'agreement among raft nodes before linearized reading' (duration: 101.753557ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:57:58.827748Z","caller":"traceutil/trace.go:171","msg":"trace[767244965] transaction","detail":"{read_only:false; response_revision:27918; number_of_response:1; }","duration":"171.238976ms","start":"2026-07-22T22:57:58.65648Z","end":"2026-07-22T22:57:58.827719Z","steps":["trace[767244965] 'process raft request' (duration: 102.809358ms)","trace[767244965] 'compare' (duration: 68.240333ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:57:58.827883Z","caller":"traceutil/trace.go:171","msg":"trace[925404002] transaction","detail":"{read_only:false; response_revision:27919; number_of_response:1; }","duration":"166.951537ms","start":"2026-07-22T22:57:58.660912Z","end":"2026-07-22T22:57:58.827864Z","steps":["trace[925404002] 'process raft request' (duration: 166.764364ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T23:00:23.252165Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-22T23:00:23.256567Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-22T23:00:23.256703Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-22T23:00:40.286198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26490} {"level":"info","ts":"2026-07-22T23:00:40.440807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26490,"took":"151.04944ms","hash":2006271385,"current-db-size-bytes":60989440,"current-db-size":"61 MB","current-db-size-in-use-bytes":37109760,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-22T23:00:40.440887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2006271385,"revision":26490,"compact-revision":23438} {"level":"info","ts":"2026-07-22T23:05:40.297122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29271} {"level":"info","ts":"2026-07-22T23:05:40.429898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29271,"took":"128.04436ms","hash":2684929456,"current-db-size-bytes":60989440,"current-db-size":"61 MB","current-db-size-in-use-bytes":33579008,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-22T23:05:40.429948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2684929456,"revision":29271,"compact-revision":26490} {"level":"info","ts":"2026-07-22T23:10:40.304321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31580} {"level":"info","ts":"2026-07-22T23:10:40.431598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31580,"took":"123.78707ms","hash":44103096,"current-db-size-bytes":60989440,"current-db-size":"61 MB","current-db-size-in-use-bytes":33259520,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-22T23:10:40.431674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":44103096,"revision":31580,"compact-revision":29271} {"level":"info","ts":"2026-07-22T23:15:40.310964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33889} {"level":"info","ts":"2026-07-22T23:15:40.474446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33889,"took":"158.853817ms","hash":807332761,"current-db-size-bytes":63299584,"current-db-size":"63 MB","current-db-size-in-use-bytes":34418688,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-22T23:15:40.474594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":807332761,"revision":33889,"compact-revision":31580} {"level":"info","ts":"2026-07-22T23:20:40.316617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36169} {"level":"info","ts":"2026-07-22T23:20:40.458506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36169,"took":"137.037951ms","hash":657934372,"current-db-size-bytes":63299584,"current-db-size":"63 MB","current-db-size-in-use-bytes":30801920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-22T23:20:40.458555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":657934372,"revision":36169,"compact-revision":33889} {"level":"info","ts":"2026-07-22T23:21:38.089516Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-22T23:21:38.094005Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-22T23:21:38.094179Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-22T23:25:40.322028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38369} {"level":"info","ts":"2026-07-22T23:25:40.4431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38369,"took":"116.707616ms","hash":359619971,"current-db-size-bytes":63299584,"current-db-size":"63 MB","current-db-size-in-use-bytes":30646272,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-22T23:25:40.443157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":359619971,"revision":38369,"compact-revision":36169} {"level":"info","ts":"2026-07-22T23:30:40.329859Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40625} {"level":"info","ts":"2026-07-22T23:30:40.456196Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40625,"took":"122.336939ms","hash":1873257674,"current-db-size-bytes":63299584,"current-db-size":"63 MB","current-db-size-in-use-bytes":31883264,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-22T23:30:40.456323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1873257674,"revision":40625,"compact-revision":38369}