{"level":"warn","ts":"2026-05-11T00:57:23.069359Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:57:23.069436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-05-11T00:57:23.069494Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:57:23.069506Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-11T00:57:23.069523Z","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-05-11T00:57:23.070052Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-05-11T00:57:23.070136Z","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.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-05-11T00:57:23.073066Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.725687ms"} {"level":"info","ts":"2026-05-11T00:57:23.080857Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-05-11T00:57:23.080956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T00:57:23.080999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-05-11T00:57:23.081031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T00:57:23.081042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-05-11T00:57:23.081092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-05-11T00:57:23.086725Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T00:57:23.090177Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T00:57:23.091821Z","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-05-11T00:57:23.094858Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T00:57:23.094991Z","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-05-11T00:57:23.095104Z","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-05-11T00:57:23.095118Z","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-05-11T00:57:23.095122Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T00:57:23.096038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-05-11T00:57:23.096198Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-11T00:57:23.097973Z","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-05-11T00:57:23.098106Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-11T00:57:23.0982Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-11T00:57:23.098396Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T00:57:23.098448Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T00:57:23.381609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T00:57:23.381669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T00:57:23.381704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-05-11T00:57:23.381731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-05-11T00:57:23.381742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-11T00:57:23.381764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-05-11T00:57:23.381829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-11T00:57:23.382965Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T00:57:23.383006Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:57:23.383568Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:23.383735Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:57:23.383921Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T00:57:23.383962Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T00:57:23.386451Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-05-11T00:57:23.386489Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T00:57:23.388712Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:23.388817Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:23.388857Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:57:58.324456Z","caller":"traceutil/trace.go:171","msg":"trace[1975729867] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"198.069943ms","start":"2026-05-11T00:57:58.126357Z","end":"2026-05-11T00:57:58.324427Z","steps":["trace[1975729867] 'process raft request' (duration: 197.53333ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:58.542525Z","caller":"traceutil/trace.go:171","msg":"trace[818870944] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"137.517084ms","start":"2026-05-11T00:57:58.404982Z","end":"2026-05-11T00:57:58.5425Z","steps":["trace[818870944] 'read index received' (duration: 126.111485ms)","trace[818870944] 'applied index is now lower than readState.Index' (duration: 11.404559ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:57:58.542647Z","caller":"traceutil/trace.go:171","msg":"trace[444188230] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"153.690637ms","start":"2026-05-11T00:57:58.388914Z","end":"2026-05-11T00:57:58.542604Z","steps":["trace[444188230] 'process raft request' (duration: 142.155285ms)","trace[444188230] 'compare' (duration: 11.308227ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:57:58.542699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.692608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:57:58.542816Z","caller":"traceutil/trace.go:171","msg":"trace[296857577] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:569; }","duration":"137.897133ms","start":"2026-05-11T00:57:58.404897Z","end":"2026-05-11T00:57:58.542795Z","steps":["trace[296857577] 'agreement among raft nodes before linearized reading' (duration: 137.708018ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:58.544408Z","caller":"traceutil/trace.go:171","msg":"trace[322093959] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"108.216447ms","start":"2026-05-11T00:57:58.436106Z","end":"2026-05-11T00:57:58.544322Z","steps":["trace[322093959] 'process raft request' (duration: 107.895759ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:59.030201Z","caller":"traceutil/trace.go:171","msg":"trace[355753954] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"362.791622ms","start":"2026-05-11T00:57:58.667389Z","end":"2026-05-11T00:57:59.030181Z","steps":["trace[355753954] 'read index received' (duration: 342.499837ms)","trace[355753954] 'applied index is now lower than readState.Index' (duration: 20.291115ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:57:59.030399Z","caller":"traceutil/trace.go:171","msg":"trace[475746769] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"416.372192ms","start":"2026-05-11T00:57:58.614017Z","end":"2026-05-11T00:57:59.030389Z","steps":["trace[475746769] 'process raft request' (duration: 395.931783ms)","trace[475746769] 'compare' (duration: 19.663281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:57:59.030752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:57:58.613993Z","time spent":"416.421974ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:57:59.030912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.527391ms","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-05-11T00:57:59.030931Z","caller":"traceutil/trace.go:171","msg":"trace[1381010047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"363.584182ms","start":"2026-05-11T00:57:58.66734Z","end":"2026-05-11T00:57:59.030924Z","steps":["trace[1381010047] 'agreement among raft nodes before linearized reading' (duration: 363.512891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:57:59.030947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:57:58.66732Z","time spent":"363.623433ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T00:57:59.033827Z","caller":"traceutil/trace.go:171","msg":"trace[1228196799] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"114.978312ms","start":"2026-05-11T00:57:58.918832Z","end":"2026-05-11T00:57:59.033811Z","steps":["trace[1228196799] 'process raft request' (duration: 114.47173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:57:59.033876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.839345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T00:57:59.033931Z","caller":"traceutil/trace.go:171","msg":"trace[1059878920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"111.941278ms","start":"2026-05-11T00:57:58.921975Z","end":"2026-05-11T00:57:59.033917Z","steps":["trace[1059878920] 'agreement among raft nodes before linearized reading' (duration: 111.761424ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:22.834745Z","caller":"traceutil/trace.go:171","msg":"trace[1700018545] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"159.88383ms","start":"2026-05-11T00:58:22.674818Z","end":"2026-05-11T00:58:22.834702Z","steps":["trace[1700018545] 'process raft request' (duration: 159.577103ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:39.396832Z","caller":"traceutil/trace.go:171","msg":"trace[231012764] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"158.642752ms","start":"2026-05-11T00:59:39.238121Z","end":"2026-05-11T00:59:39.396764Z","steps":["trace[231012764] 'process raft request' (duration: 158.475698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:39.954345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.385933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:59:39.954534Z","caller":"traceutil/trace.go:171","msg":"trace[631552537] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1902; }","duration":"178.579555ms","start":"2026-05-11T00:59:39.775938Z","end":"2026-05-11T00:59:39.954518Z","steps":["trace[631552537] 'read index received' (duration: 8.804984ms)","trace[631552537] 'applied index is now lower than readState.Index' (duration: 169.773521ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:39.954627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.693158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:39.954661Z","caller":"traceutil/trace.go:171","msg":"trace[2107948449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1830; }","duration":"178.764459ms","start":"2026-05-11T00:59:39.775888Z","end":"2026-05-11T00:59:39.954652Z","steps":["trace[2107948449] 'agreement among raft nodes before linearized reading' (duration: 178.684127ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:39.954859Z","caller":"traceutil/trace.go:171","msg":"trace[739874585] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"279.825007ms","start":"2026-05-11T00:59:39.674983Z","end":"2026-05-11T00:59:39.954808Z","steps":["trace[739874585] 'process raft request' (duration: 109.746779ms)","trace[739874585] 'compare' (duration: 169.251518ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:39.954869Z","caller":"traceutil/trace.go:171","msg":"trace[1108155686] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"277.191363ms","start":"2026-05-11T00:59:39.677641Z","end":"2026-05-11T00:59:39.954832Z","steps":["trace[1108155686] 'process raft request' (duration: 276.819365ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:01:48.727976Z","caller":"traceutil/trace.go:171","msg":"trace[1083385238] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"147.352227ms","start":"2026-05-11T01:01:48.580598Z","end":"2026-05-11T01:01:48.727951Z","steps":["trace[1083385238] 'process raft request' (duration: 135.950346ms)","trace[1083385238] 'compare' (duration: 11.212697ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:04:13.489743Z","caller":"traceutil/trace.go:171","msg":"trace[1714078168] transaction","detail":"{read_only:false; response_revision:3736; number_of_response:1; }","duration":"145.710741ms","start":"2026-05-11T01:04:13.344006Z","end":"2026-05-11T01:04:13.489717Z","steps":["trace[1714078168] 'process raft request' (duration: 145.559297ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:04:13.623242Z","caller":"traceutil/trace.go:171","msg":"trace[245957888] linearizableReadLoop","detail":"{readStateIndex:3881; appliedIndex:3879; }","duration":"262.432784ms","start":"2026-05-11T01:04:13.360774Z","end":"2026-05-11T01:04:13.623207Z","steps":["trace[245957888] 'read index received' (duration: 128.8829ms)","trace[245957888] 'applied index is now lower than readState.Index' (duration: 133.548104ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:04:13.623405Z","caller":"traceutil/trace.go:171","msg":"trace[884850634] transaction","detail":"{read_only:false; response_revision:3737; number_of_response:1; }","duration":"274.728885ms","start":"2026-05-11T01:04:13.348637Z","end":"2026-05-11T01:04:13.623366Z","steps":["trace[884850634] 'process raft request' (duration: 264.907549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:04:13.623743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.855835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T01:04:13.623813Z","caller":"traceutil/trace.go:171","msg":"trace[851097971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3737; }","duration":"258.974528ms","start":"2026-05-11T01:04:13.364825Z","end":"2026-05-11T01:04:13.6238Z","steps":["trace[851097971] 'agreement among raft nodes before linearized reading' (duration: 258.60624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:04:13.623887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.051027ms","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-05-11T01:04:13.62399Z","caller":"traceutil/trace.go:171","msg":"trace[891050304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3737; }","duration":"263.223911ms","start":"2026-05-11T01:04:13.360728Z","end":"2026-05-11T01:04:13.623952Z","steps":["trace[891050304] 'agreement among raft nodes before linearized reading' (duration: 262.659649ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:29.336794Z","caller":"traceutil/trace.go:171","msg":"trace[560974801] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"104.924988ms","start":"2026-05-11T01:05:29.231844Z","end":"2026-05-11T01:05:29.336769Z","steps":["trace[560974801] 'process raft request' (duration: 95.926546ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:32.592712Z","caller":"traceutil/trace.go:171","msg":"trace[1754409921] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"129.256738ms","start":"2026-05-11T01:05:32.463432Z","end":"2026-05-11T01:05:32.592689Z","steps":["trace[1754409921] 'process raft request' (duration: 129.137466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:36.127956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.490081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-11T01:05:36.127982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.879008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T01:05:36.128067Z","caller":"traceutil/trace.go:171","msg":"trace[221754644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4914; }","duration":"127.648114ms","start":"2026-05-11T01:05:36.000398Z","end":"2026-05-11T01:05:36.128046Z","steps":["trace[221754644] 'range keys from in-memory index tree' (duration: 127.310947ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:36.128076Z","caller":"traceutil/trace.go:171","msg":"trace[981532491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4914; }","duration":"127.00643ms","start":"2026-05-11T01:05:36.00105Z","end":"2026-05-11T01:05:36.128056Z","steps":["trace[981532491] 'range keys from in-memory index tree' (duration: 126.750325ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:36.305226Z","caller":"traceutil/trace.go:171","msg":"trace[331651450] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"165.190724ms","start":"2026-05-11T01:05:36.13999Z","end":"2026-05-11T01:05:36.305181Z","steps":["trace[331651450] 'process raft request' (duration: 146.028045ms)","trace[331651450] 'compare' (duration: 18.491985ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:46.505763Z","caller":"traceutil/trace.go:171","msg":"trace[1588212331] linearizableReadLoop","detail":"{readStateIndex:5316; appliedIndex:5315; }","duration":"155.531717ms","start":"2026-05-11T01:05:46.350204Z","end":"2026-05-11T01:05:46.505736Z","steps":["trace[1588212331] 'read index received' (duration: 115.425442ms)","trace[1588212331] 'applied index is now lower than readState.Index' (duration: 40.105285ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:46.505924Z","caller":"traceutil/trace.go:171","msg":"trace[593424028] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"160.637077ms","start":"2026-05-11T01:05:46.345269Z","end":"2026-05-11T01:05:46.505906Z","steps":["trace[593424028] 'process raft request' (duration: 120.407329ms)","trace[593424028] 'compare' (duration: 39.904661ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:05:46.506039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.93343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-11T01:05:46.50612Z","caller":"traceutil/trace.go:171","msg":"trace[1720178245] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:5122; }","duration":"151.048642ms","start":"2026-05-11T01:05:46.355052Z","end":"2026-05-11T01:05:46.506101Z","steps":["trace[1720178245] 'agreement among raft nodes before linearized reading' (duration: 150.869978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:46.50628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.078299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T01:05:46.50633Z","caller":"traceutil/trace.go:171","msg":"trace[1052259451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5122; }","duration":"156.158951ms","start":"2026-05-11T01:05:46.350159Z","end":"2026-05-11T01:05:46.506318Z","steps":["trace[1052259451] 'agreement among raft nodes before linearized reading' (duration: 156.048438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:59.962573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.151513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:05:59.962699Z","caller":"traceutil/trace.go:171","msg":"trace[919436887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5380; }","duration":"187.336007ms","start":"2026-05-11T01:05:59.775344Z","end":"2026-05-11T01:05:59.96268Z","steps":["trace[919436887] 'range keys from in-memory index tree' (duration: 187.052861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:59.963069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.384509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:05:59.963246Z","caller":"traceutil/trace.go:171","msg":"trace[771099085] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5380; }","duration":"210.994712ms","start":"2026-05-11T01:05:59.752222Z","end":"2026-05-11T01:05:59.963216Z","steps":["trace[771099085] 'count revisions from in-memory index tree' (duration: 210.310057ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:06:00.011181Z","caller":"traceutil/trace.go:171","msg":"trace[1376655453] linearizableReadLoop","detail":"{readStateIndex:5581; appliedIndex:5580; }","duration":"162.490387ms","start":"2026-05-11T01:05:59.848665Z","end":"2026-05-11T01:06:00.011156Z","steps":["trace[1376655453] 'read index received' (duration: 162.330424ms)","trace[1376655453] 'applied index is now lower than readState.Index' (duration: 158.893µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:06:00.011293Z","caller":"traceutil/trace.go:171","msg":"trace[727527623] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"162.825385ms","start":"2026-05-11T01:05:59.848455Z","end":"2026-05-11T01:06:00.011281Z","steps":["trace[727527623] 'process raft request' (duration: 162.562049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:06:00.011448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.767762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:06:00.011491Z","caller":"traceutil/trace.go:171","msg":"trace[100024217] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5381; }","duration":"162.878366ms","start":"2026-05-11T01:05:59.848601Z","end":"2026-05-11T01:06:00.01148Z","steps":["trace[100024217] 'agreement among raft nodes before linearized reading' (duration: 162.798094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:06:00.038811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.309702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d62679a9-ea55-46f2-8ac8-7e597d4f771b\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-11T01:06:00.038893Z","caller":"traceutil/trace.go:171","msg":"trace[364404823] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d62679a9-ea55-46f2-8ac8-7e597d4f771b; range_end:; response_count:1; response_revision:5382; }","duration":"131.426974ms","start":"2026-05-11T01:05:59.907446Z","end":"2026-05-11T01:06:00.038873Z","steps":["trace[364404823] 'agreement among raft nodes before linearized reading' (duration: 131.2002ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:06:10.945721Z","caller":"traceutil/trace.go:171","msg":"trace[499424189] linearizableReadLoop","detail":"{readStateIndex:5680; appliedIndex:5677; }","duration":"171.149321ms","start":"2026-05-11T01:06:10.774549Z","end":"2026-05-11T01:06:10.945698Z","steps":["trace[499424189] 'read index received' (duration: 101.507616ms)","trace[499424189] 'applied index is now lower than readState.Index' (duration: 69.640865ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:06:10.945728Z","caller":"traceutil/trace.go:171","msg":"trace[741150365] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"202.438209ms","start":"2026-05-11T01:06:10.743254Z","end":"2026-05-11T01:06:10.945693Z","steps":["trace[741150365] 'process raft request' (duration: 132.786544ms)","trace[741150365] 'compare' (duration: 69.323588ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:06:10.945764Z","caller":"traceutil/trace.go:171","msg":"trace[1570643836] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"201.414097ms","start":"2026-05-11T01:06:10.744327Z","end":"2026-05-11T01:06:10.945741Z","steps":["trace[1570643836] 'process raft request' (duration: 201.248134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:06:10.945917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.346817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:06:10.945953Z","caller":"traceutil/trace.go:171","msg":"trace[976796401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5478; }","duration":"171.428568ms","start":"2026-05-11T01:06:10.774514Z","end":"2026-05-11T01:06:10.945942Z","steps":["trace[976796401] 'agreement among raft nodes before linearized reading' (duration: 171.309946ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:06:10.946014Z","caller":"traceutil/trace.go:171","msg":"trace[129798450] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"201.547ms","start":"2026-05-11T01:06:10.744425Z","end":"2026-05-11T01:06:10.945972Z","steps":["trace[129798450] 'process raft request' (duration: 201.234492ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:07:23.419011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2962} {"level":"info","ts":"2026-05-11T01:07:23.491938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2962,"took":"70.753256ms","hash":2615846535,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":33939456,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T01:07:23.491993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2615846535,"revision":2962,"compact-revision":-1} {"level":"info","ts":"2026-05-11T01:11:46.819306Z","caller":"traceutil/trace.go:171","msg":"trace[202833259] linearizableReadLoop","detail":"{readStateIndex:8334; appliedIndex:8333; }","duration":"130.102547ms","start":"2026-05-11T01:11:46.689175Z","end":"2026-05-11T01:11:46.819277Z","steps":["trace[202833259] 'read index received' (duration: 129.820271ms)","trace[202833259] 'applied index is now lower than readState.Index' (duration: 280.776µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:11:46.819413Z","caller":"traceutil/trace.go:171","msg":"trace[1703370088] transaction","detail":"{read_only:false; response_revision:8051; number_of_response:1; }","duration":"226.025635ms","start":"2026-05-11T01:11:46.593346Z","end":"2026-05-11T01:11:46.819372Z","steps":["trace[1703370088] 'process raft request' (duration: 225.762369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:46.819589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.404503ms","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-05-11T01:11:46.819639Z","caller":"traceutil/trace.go:171","msg":"trace[269008511] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8051; }","duration":"130.514735ms","start":"2026-05-11T01:11:46.68911Z","end":"2026-05-11T01:11:46.819625Z","steps":["trace[269008511] 'agreement among raft nodes before linearized reading' (duration: 130.335411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:46.819686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.608244ms","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-05-11T01:11:46.819731Z","caller":"traceutil/trace.go:171","msg":"trace[1079309867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8051; }","duration":"113.708156ms","start":"2026-05-11T01:11:46.706012Z","end":"2026-05-11T01:11:46.81972Z","steps":["trace[1079309867] 'agreement among raft nodes before linearized reading' (duration: 113.561733ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:47.011441Z","caller":"traceutil/trace.go:171","msg":"trace[1521224917] transaction","detail":"{read_only:false; response_revision:8052; number_of_response:1; }","duration":"186.966571ms","start":"2026-05-11T01:11:46.824448Z","end":"2026-05-11T01:11:47.011415Z","steps":["trace[1521224917] 'process raft request' (duration: 134.266076ms)","trace[1521224917] 'compare' (duration: 52.535932ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:11:47.011877Z","caller":"traceutil/trace.go:171","msg":"trace[1855635624] transaction","detail":"{read_only:false; response_revision:8055; number_of_response:1; }","duration":"121.271058ms","start":"2026-05-11T01:11:46.890599Z","end":"2026-05-11T01:11:47.01187Z","steps":["trace[1855635624] 'process raft request' (duration: 121.242247ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:47.011955Z","caller":"traceutil/trace.go:171","msg":"trace[1211696467] transaction","detail":"{read_only:false; response_revision:8054; number_of_response:1; }","duration":"186.206805ms","start":"2026-05-11T01:11:46.825743Z","end":"2026-05-11T01:11:47.01195Z","steps":["trace[1211696467] 'process raft request' (duration: 186.046851ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:47.011924Z","caller":"traceutil/trace.go:171","msg":"trace[242609289] transaction","detail":"{read_only:false; response_revision:8053; number_of_response:1; }","duration":"186.90406ms","start":"2026-05-11T01:11:46.824994Z","end":"2026-05-11T01:11:47.011898Z","steps":["trace[242609289] 'process raft request' (duration: 186.500571ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:59.47142Z","caller":"traceutil/trace.go:171","msg":"trace[714164291] transaction","detail":"{read_only:false; response_revision:8118; number_of_response:1; }","duration":"205.038956ms","start":"2026-05-11T01:11:59.266341Z","end":"2026-05-11T01:11:59.47138Z","steps":["trace[714164291] 'process raft request' (duration: 150.716367ms)","trace[714164291] 'compare' (duration: 53.633915ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:11:59.471984Z","caller":"traceutil/trace.go:171","msg":"trace[1560398399] linearizableReadLoop","detail":"{readStateIndex:8405; appliedIndex:8403; }","duration":"172.452771ms","start":"2026-05-11T01:11:59.299512Z","end":"2026-05-11T01:11:59.471965Z","steps":["trace[1560398399] 'read index received' (duration: 117.484127ms)","trace[1560398399] 'applied index is now lower than readState.Index' (duration: 54.967544ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:11:59.47212Z","caller":"traceutil/trace.go:171","msg":"trace[881316816] transaction","detail":"{read_only:false; response_revision:8119; number_of_response:1; }","duration":"199.346154ms","start":"2026-05-11T01:11:59.272726Z","end":"2026-05-11T01:11:59.472072Z","steps":["trace[881316816] 'process raft request' (duration: 198.559427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:59.473644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.098786ms","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-05-11T01:11:59.4737Z","caller":"traceutil/trace.go:171","msg":"trace[2132273269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8119; }","duration":"174.213448ms","start":"2026-05-11T01:11:59.299473Z","end":"2026-05-11T01:11:59.473686Z","steps":["trace[2132273269] 'agreement among raft nodes before linearized reading' (duration: 172.559094ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:12:00.682592Z","caller":"traceutil/trace.go:171","msg":"trace[544702978] transaction","detail":"{read_only:false; response_revision:8123; number_of_response:1; }","duration":"122.382163ms","start":"2026-05-11T01:12:00.560174Z","end":"2026-05-11T01:12:00.682556Z","steps":["trace[544702978] 'process raft request' (duration: 122.162668ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:12:23.425491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6080} {"level":"info","ts":"2026-05-11T01:12:23.51881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6080,"took":"91.469587ms","hash":1760760001,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":23232512,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-11T01:12:23.518879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1760760001,"revision":6080,"compact-revision":2962} {"level":"info","ts":"2026-05-11T01:12:40.459058Z","caller":"traceutil/trace.go:171","msg":"trace[879653522] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"175.861186ms","start":"2026-05-11T01:12:40.283152Z","end":"2026-05-11T01:12:40.459013Z","steps":["trace[879653522] 'process raft request' (duration: 115.795432ms)","trace[879653522] 'compare' (duration: 59.329067ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:12:41.400907Z","caller":"traceutil/trace.go:171","msg":"trace[10066777] transaction","detail":"{read_only:false; response_revision:8437; number_of_response:1; }","duration":"151.607112ms","start":"2026-05-11T01:12:41.249271Z","end":"2026-05-11T01:12:41.400878Z","steps":["trace[10066777] 'process raft request' (duration: 151.450528ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:12:41.454233Z","caller":"traceutil/trace.go:171","msg":"trace[1103623215] linearizableReadLoop","detail":"{readStateIndex:8737; appliedIndex:8735; }","duration":"151.717214ms","start":"2026-05-11T01:12:41.302484Z","end":"2026-05-11T01:12:41.454201Z","steps":["trace[1103623215] 'read index received' (duration: 98.232855ms)","trace[1103623215] 'applied index is now lower than readState.Index' (duration: 53.483089ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:12:41.454357Z","caller":"traceutil/trace.go:171","msg":"trace[417622243] transaction","detail":"{read_only:false; response_revision:8438; number_of_response:1; }","duration":"191.165273ms","start":"2026-05-11T01:12:41.263157Z","end":"2026-05-11T01:12:41.454322Z","steps":["trace[417622243] 'process raft request' (duration: 167.824228ms)","trace[417622243] 'compare' (duration: 23.088909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:12:41.454535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.46151ms","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-05-11T01:12:41.454588Z","caller":"traceutil/trace.go:171","msg":"trace[245201469] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8438; }","duration":"137.557682ms","start":"2026-05-11T01:12:41.317014Z","end":"2026-05-11T01:12:41.454572Z","steps":["trace[245201469] 'agreement among raft nodes before linearized reading' (duration: 137.369248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:12:41.454567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.059461ms","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-05-11T01:12:41.454693Z","caller":"traceutil/trace.go:171","msg":"trace[269960255] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8438; }","duration":"152.247335ms","start":"2026-05-11T01:12:41.302424Z","end":"2026-05-11T01:12:41.454671Z","steps":["trace[269960255] 'agreement among raft nodes before linearized reading' (duration: 151.919188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:12:41.454698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.951561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-11T01:12:41.454723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.861381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T01:12:41.454789Z","caller":"traceutil/trace.go:171","msg":"trace[450542999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8438; }","duration":"119.093174ms","start":"2026-05-11T01:12:41.335674Z","end":"2026-05-11T01:12:41.454767Z","steps":["trace[450542999] 'agreement among raft nodes before linearized reading' (duration: 118.796628ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:12:41.45486Z","caller":"traceutil/trace.go:171","msg":"trace[1558090737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8438; }","duration":"120.050056ms","start":"2026-05-11T01:12:41.334779Z","end":"2026-05-11T01:12:41.454829Z","steps":["trace[1558090737] 'agreement among raft nodes before linearized reading' (duration: 119.737559ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:13:39.951808Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002483.wal"} {"level":"info","ts":"2026-05-11T01:15:04.027748Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:15:04.032705Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-11T01:15:04.032854Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-11T01:17:23.434886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8283} {"level":"info","ts":"2026-05-11T01:17:23.529336Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8283,"took":"90.401774ms","hash":1377769931,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":26001408,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-11T01:17:23.529429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1377769931,"revision":8283,"compact-revision":6080} {"level":"info","ts":"2026-05-11T01:22:23.442347Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10936} {"level":"info","ts":"2026-05-11T01:22:23.523796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10936,"took":"78.263903ms","hash":1108016334,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":27607040,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T01:22:23.523871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1108016334,"revision":10936,"compact-revision":8283} {"level":"info","ts":"2026-05-11T01:23:59.888945Z","caller":"traceutil/trace.go:171","msg":"trace[1167469849] transaction","detail":"{read_only:false; response_revision:13595; number_of_response:1; }","duration":"100.298114ms","start":"2026-05-11T01:23:59.788598Z","end":"2026-05-11T01:23:59.888896Z","steps":["trace[1167469849] 'process raft request' (duration: 99.856874ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:24:00.54902Z","caller":"traceutil/trace.go:171","msg":"trace[1671769696] linearizableReadLoop","detail":"{readStateIndex:14064; appliedIndex:14062; }","duration":"209.225482ms","start":"2026-05-11T01:24:00.339762Z","end":"2026-05-11T01:24:00.548987Z","steps":["trace[1671769696] 'read index received' (duration: 131.367728ms)","trace[1671769696] 'applied index is now lower than readState.Index' (duration: 77.856484ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:24:00.549219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.425826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:24:00.549277Z","caller":"traceutil/trace.go:171","msg":"trace[989833777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13599; }","duration":"209.561309ms","start":"2026-05-11T01:24:00.3397Z","end":"2026-05-11T01:24:00.549261Z","steps":["trace[989833777] 'agreement among raft nodes before linearized reading' (duration: 209.412506ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:24:00.54945Z","caller":"traceutil/trace.go:171","msg":"trace[1496569864] transaction","detail":"{read_only:false; response_revision:13598; number_of_response:1; }","duration":"360.113678ms","start":"2026-05-11T01:24:00.189294Z","end":"2026-05-11T01:24:00.549408Z","steps":["trace[1496569864] 'process raft request' (duration: 281.905876ms)","trace[1496569864] 'compare' (duration: 77.289723ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:24:00.549626Z","caller":"traceutil/trace.go:171","msg":"trace[1939240559] transaction","detail":"{read_only:false; response_revision:13599; number_of_response:1; }","duration":"358.77343ms","start":"2026-05-11T01:24:00.190833Z","end":"2026-05-11T01:24:00.549607Z","steps":["trace[1939240559] 'process raft request' (duration: 358.006434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:24:00.549783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:24:00.190811Z","time spent":"358.875682ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:24:00.549822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:24:00.189265Z","time spent":"360.323943ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:25:42.059044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.059428ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:25:42.059702Z","caller":"traceutil/trace.go:171","msg":"trace[1794137778] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14679; }","duration":"157.739733ms","start":"2026-05-11T01:25:41.901934Z","end":"2026-05-11T01:25:42.059673Z","steps":["trace[1794137778] 'range keys from in-memory index tree' (duration: 157.033237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:25:42.059284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.827305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-11T01:25:42.059916Z","caller":"traceutil/trace.go:171","msg":"trace[1548416136] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14679; }","duration":"195.476239ms","start":"2026-05-11T01:25:41.864407Z","end":"2026-05-11T01:25:42.059884Z","steps":["trace[1548416136] 'range keys from in-memory index tree' (duration: 194.681052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:25:42.059314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.915204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-05-11T01:25:42.059372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.617678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:25:42.060059Z","caller":"traceutil/trace.go:171","msg":"trace[1729181362] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14679; }","duration":"149.6651ms","start":"2026-05-11T01:25:41.910363Z","end":"2026-05-11T01:25:42.060028Z","steps":["trace[1729181362] 'range keys from in-memory index tree' (duration: 148.804871ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:25:42.060123Z","caller":"traceutil/trace.go:171","msg":"trace[987879211] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14679; }","duration":"149.358274ms","start":"2026-05-11T01:25:41.910747Z","end":"2026-05-11T01:25:42.060106Z","steps":["trace[987879211] 'range keys from in-memory index tree' (duration: 148.540916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:25:42.059327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.379289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-11T01:25:42.060236Z","caller":"traceutil/trace.go:171","msg":"trace[743032566] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14679; }","duration":"147.347161ms","start":"2026-05-11T01:25:41.912876Z","end":"2026-05-11T01:25:42.060223Z","steps":["trace[743032566] 'range keys from in-memory index tree' (duration: 146.090673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:25:42.059477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.869116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T01:25:42.060331Z","caller":"traceutil/trace.go:171","msg":"trace[1626917896] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:14679; }","duration":"195.835237ms","start":"2026-05-11T01:25:41.864479Z","end":"2026-05-11T01:25:42.060315Z","steps":["trace[1626917896] 'count revisions from in-memory index tree' (duration: 194.693762ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:25:43.06733Z","caller":"traceutil/trace.go:171","msg":"trace[586800678] transaction","detail":"{read_only:false; response_revision:14684; number_of_response:1; }","duration":"127.020025ms","start":"2026-05-11T01:25:42.940283Z","end":"2026-05-11T01:25:43.067303Z","steps":["trace[586800678] 'process raft request' (duration: 126.843162ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:25:43.177383Z","caller":"traceutil/trace.go:171","msg":"trace[1839004651] transaction","detail":"{read_only:false; response_revision:14685; number_of_response:1; }","duration":"236.159219ms","start":"2026-05-11T01:25:42.941177Z","end":"2026-05-11T01:25:43.177336Z","steps":["trace[1839004651] 'process raft request' (duration: 235.953814ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:27:23.448045Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12966} {"level":"info","ts":"2026-05-11T01:27:23.52816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12966,"took":"77.067024ms","hash":1324512276,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":30662656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T01:27:23.52823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1324512276,"revision":12966,"compact-revision":10936} {"level":"info","ts":"2026-05-11T01:30:56.375956Z","caller":"traceutil/trace.go:171","msg":"trace[456195044] linearizableReadLoop","detail":"{readStateIndex:17672; appliedIndex:17671; }","duration":"165.734037ms","start":"2026-05-11T01:30:56.210185Z","end":"2026-05-11T01:30:56.375919Z","steps":["trace[456195044] 'read index received' (duration: 165.542873ms)","trace[456195044] 'applied index is now lower than readState.Index' (duration: 190.104µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:30:56.376256Z","caller":"traceutil/trace.go:171","msg":"trace[1835044895] transaction","detail":"{read_only:false; response_revision:17095; number_of_response:1; }","duration":"184.685992ms","start":"2026-05-11T01:30:56.191555Z","end":"2026-05-11T01:30:56.376241Z","steps":["trace[1835044895] 'process raft request' (duration: 184.224052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:30:56.376487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.291809ms","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-05-11T01:30:56.376528Z","caller":"traceutil/trace.go:171","msg":"trace[318886271] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17095; }","duration":"166.3764ms","start":"2026-05-11T01:30:56.210142Z","end":"2026-05-11T01:30:56.376519Z","steps":["trace[318886271] 'agreement among raft nodes before linearized reading' (duration: 166.294089ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:30:56.401335Z","caller":"traceutil/trace.go:171","msg":"trace[414690075] transaction","detail":"{read_only:false; response_revision:17096; number_of_response:1; }","duration":"167.895634ms","start":"2026-05-11T01:30:56.233411Z","end":"2026-05-11T01:30:56.401306Z","steps":["trace[414690075] 'process raft request' (duration: 167.610718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:30:56.401659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.92693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:30:56.40175Z","caller":"traceutil/trace.go:171","msg":"trace[1087188682] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:17097; }","duration":"114.054043ms","start":"2026-05-11T01:30:56.287657Z","end":"2026-05-11T01:30:56.401711Z","steps":["trace[1087188682] 'agreement among raft nodes before linearized reading' (duration: 113.943451ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:30:56.712999Z","caller":"traceutil/trace.go:171","msg":"trace[1877999888] linearizableReadLoop","detail":"{readStateIndex:17675; appliedIndex:17674; }","duration":"268.038421ms","start":"2026-05-11T01:30:56.444913Z","end":"2026-05-11T01:30:56.712951Z","steps":["trace[1877999888] 'read index received' (duration: 185.848637ms)","trace[1877999888] 'applied index is now lower than readState.Index' (duration: 82.187554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:30:56.713347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.401269ms","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-05-11T01:30:56.713418Z","caller":"traceutil/trace.go:171","msg":"trace[351696248] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:17097; }","duration":"268.504821ms","start":"2026-05-11T01:30:56.444891Z","end":"2026-05-11T01:30:56.713396Z","steps":["trace[351696248] 'agreement among raft nodes before linearized reading' (duration: 268.269096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:30:56.713836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.942622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:30:56.713963Z","caller":"traceutil/trace.go:171","msg":"trace[644495579] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:17097; }","duration":"107.128876ms","start":"2026-05-11T01:30:56.606816Z","end":"2026-05-11T01:30:56.713945Z","steps":["trace[644495579] 'agreement among raft nodes before linearized reading' (duration: 106.912052ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:30:56.840283Z","caller":"traceutil/trace.go:171","msg":"trace[184645553] transaction","detail":"{read_only:false; response_revision:17099; number_of_response:1; }","duration":"122.27954ms","start":"2026-05-11T01:30:56.717974Z","end":"2026-05-11T01:30:56.840253Z","steps":["trace[184645553] 'process raft request' (duration: 110.474578ms)","trace[184645553] 'compare' (duration: 11.24598ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:31:08.55494Z","caller":"traceutil/trace.go:171","msg":"trace[1944826147] transaction","detail":"{read_only:false; response_revision:17284; number_of_response:1; }","duration":"109.244712ms","start":"2026-05-11T01:31:08.445667Z","end":"2026-05-11T01:31:08.554912Z","steps":["trace[1944826147] 'process raft request' (duration: 109.095699ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:31:08.555131Z","caller":"traceutil/trace.go:171","msg":"trace[872816110] transaction","detail":"{read_only:false; response_revision:17285; number_of_response:1; }","duration":"109.373855ms","start":"2026-05-11T01:31:08.445723Z","end":"2026-05-11T01:31:08.555097Z","steps":["trace[872816110] 'process raft request' (duration: 109.14192ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:32:23.453631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15366} {"level":"info","ts":"2026-05-11T01:32:23.54882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15366,"took":"92.707059ms","hash":72581154,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":33906688,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T01:32:23.548885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":72581154,"revision":15366,"compact-revision":12966} {"level":"info","ts":"2026-05-11T01:35:22.789322Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:35:22.794083Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-11T01:35:22.79419Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-11T01:36:12.18537Z","caller":"traceutil/trace.go:171","msg":"trace[262996434] transaction","detail":"{read_only:false; response_revision:19615; number_of_response:1; }","duration":"106.820737ms","start":"2026-05-11T01:36:12.078506Z","end":"2026-05-11T01:36:12.185327Z","steps":["trace[262996434] 'process raft request' (duration: 106.49127ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:17.838883Z","caller":"traceutil/trace.go:171","msg":"trace[823225903] transaction","detail":"{read_only:false; response_revision:19645; number_of_response:1; }","duration":"146.880211ms","start":"2026-05-11T01:36:17.691978Z","end":"2026-05-11T01:36:17.838858Z","steps":["trace[823225903] 'process raft request' (duration: 146.248248ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:17.839141Z","caller":"traceutil/trace.go:171","msg":"trace[86178701] transaction","detail":"{read_only:false; response_revision:19646; number_of_response:1; }","duration":"124.491694ms","start":"2026-05-11T01:36:17.714638Z","end":"2026-05-11T01:36:17.83913Z","steps":["trace[86178701] 'process raft request' (duration: 123.769848ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:18.354984Z","caller":"traceutil/trace.go:171","msg":"trace[1271022269] transaction","detail":"{read_only:false; response_revision:19647; number_of_response:1; }","duration":"132.858193ms","start":"2026-05-11T01:36:18.222099Z","end":"2026-05-11T01:36:18.354957Z","steps":["trace[1271022269] 'process raft request' (duration: 132.647228ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:19.091683Z","caller":"traceutil/trace.go:171","msg":"trace[1043125457] transaction","detail":"{read_only:false; response_revision:19649; number_of_response:1; }","duration":"147.082986ms","start":"2026-05-11T01:36:18.944564Z","end":"2026-05-11T01:36:19.091647Z","steps":["trace[1043125457] 'process raft request' (duration: 146.82396ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:19.104959Z","caller":"traceutil/trace.go:171","msg":"trace[1293716414] transaction","detail":"{read_only:false; response_revision:19650; number_of_response:1; }","duration":"158.919077ms","start":"2026-05-11T01:36:18.945992Z","end":"2026-05-11T01:36:19.104911Z","steps":["trace[1293716414] 'process raft request' (duration: 158.648942ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:19.104987Z","caller":"traceutil/trace.go:171","msg":"trace[392442840] transaction","detail":"{read_only:false; response_revision:19651; number_of_response:1; }","duration":"114.615923ms","start":"2026-05-11T01:36:18.990326Z","end":"2026-05-11T01:36:19.104942Z","steps":["trace[392442840] 'process raft request' (duration: 114.49681ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:19.348319Z","caller":"traceutil/trace.go:171","msg":"trace[1447921755] transaction","detail":"{read_only:false; response_revision:19654; number_of_response:1; }","duration":"237.525544ms","start":"2026-05-11T01:36:19.110756Z","end":"2026-05-11T01:36:19.348282Z","steps":["trace[1447921755] 'process raft request' (duration: 237.463173ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:36:19.348556Z","caller":"traceutil/trace.go:171","msg":"trace[372955365] transaction","detail":"{read_only:false; response_revision:19653; number_of_response:1; }","duration":"237.862501ms","start":"2026-05-11T01:36:19.110659Z","end":"2026-05-11T01:36:19.348522Z","steps":["trace[372955365] 'process raft request' (duration: 153.756768ms)","trace[372955365] 'compare' (duration: 83.598162ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:37:23.459948Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17996} {"level":"info","ts":"2026-05-11T01:37:23.578576Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17996,"took":"114.721615ms","hash":2506975849,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":30150656,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T01:37:23.578841Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2506975849,"revision":17996,"compact-revision":15366} {"level":"info","ts":"2026-05-11T01:40:03.72711Z","caller":"traceutil/trace.go:171","msg":"trace[1687980882] linearizableReadLoop","detail":"{readStateIndex:22707; appliedIndex:22706; }","duration":"118.565987ms","start":"2026-05-11T01:40:03.608521Z","end":"2026-05-11T01:40:03.727087Z","steps":["trace[1687980882] 'read index received' (duration: 118.408454ms)","trace[1687980882] 'applied index is now lower than readState.Index' (duration: 156.293µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:40:03.727447Z","caller":"traceutil/trace.go:171","msg":"trace[1992765650] transaction","detail":"{read_only:false; response_revision:21980; number_of_response:1; }","duration":"229.720297ms","start":"2026-05-11T01:40:03.497706Z","end":"2026-05-11T01:40:03.727426Z","steps":["trace[1992765650] 'process raft request' (duration: 229.236507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:40:03.727772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.238773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capo-system/capo-controller-manager-78487c64cd-lbhfk\" ","response":"range_response_count:1 size:4755"} {"level":"info","ts":"2026-05-11T01:40:03.727859Z","caller":"traceutil/trace.go:171","msg":"trace[851650117] range","detail":"{range_begin:/registry/pods/capo-system/capo-controller-manager-78487c64cd-lbhfk; range_end:; response_count:1; response_revision:21980; }","duration":"119.389506ms","start":"2026-05-11T01:40:03.608457Z","end":"2026-05-11T01:40:03.727846Z","steps":["trace[851650117] 'agreement among raft nodes before linearized reading' (duration: 119.203451ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:40:03.858562Z","caller":"traceutil/trace.go:171","msg":"trace[561975201] transaction","detail":"{read_only:false; response_revision:21981; number_of_response:1; }","duration":"121.235934ms","start":"2026-05-11T01:40:03.737298Z","end":"2026-05-11T01:40:03.858534Z","steps":["trace[561975201] 'process raft request' (duration: 121.04095ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:41:30.735602Z","caller":"traceutil/trace.go:171","msg":"trace[689735996] transaction","detail":"{read_only:false; response_revision:22930; number_of_response:1; }","duration":"116.086616ms","start":"2026-05-11T01:41:30.619487Z","end":"2026-05-11T01:41:30.735574Z","steps":["trace[689735996] 'process raft request' (duration: 115.902951ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:42:23.467576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20249} {"level":"info","ts":"2026-05-11T01:42:23.584603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20249,"took":"111.176491ms","hash":1015057220,"current-db-size-bytes":58294272,"current-db-size":"58 MB","current-db-size-in-use-bytes":42512384,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-11T01:42:23.584711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1015057220,"revision":20249,"compact-revision":17996} {"level":"info","ts":"2026-05-11T01:43:25.396856Z","caller":"traceutil/trace.go:171","msg":"trace[186555241] transaction","detail":"{read_only:false; response_revision:24055; number_of_response:1; }","duration":"107.87624ms","start":"2026-05-11T01:43:25.288956Z","end":"2026-05-11T01:43:25.396832Z","steps":["trace[186555241] 'process raft request' (duration: 107.727987ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:25.640776Z","caller":"traceutil/trace.go:171","msg":"trace[204950639] linearizableReadLoop","detail":"{readStateIndex:24838; appliedIndex:24837; }","duration":"234.940159ms","start":"2026-05-11T01:43:25.405786Z","end":"2026-05-11T01:43:25.640726Z","steps":["trace[204950639] 'read index received' (duration: 224.894564ms)","trace[204950639] 'applied index is now lower than readState.Index' (duration: 10.043765ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:25.640809Z","caller":"traceutil/trace.go:171","msg":"trace[1379410549] transaction","detail":"{read_only:false; response_revision:24056; number_of_response:1; }","duration":"274.251867ms","start":"2026-05-11T01:43:25.366532Z","end":"2026-05-11T01:43:25.640784Z","steps":["trace[1379410549] 'process raft request' (duration: 264.187752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:25.641073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.254565ms","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-05-11T01:43:25.641339Z","caller":"traceutil/trace.go:171","msg":"trace[455811749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24056; }","duration":"235.543741ms","start":"2026-05-11T01:43:25.405751Z","end":"2026-05-11T01:43:25.641295Z","steps":["trace[455811749] 'agreement among raft nodes before linearized reading' (duration: 235.101821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:25.642512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.258156ms","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-05-11T01:43:25.642576Z","caller":"traceutil/trace.go:171","msg":"trace[1539438709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24057; }","duration":"235.355197ms","start":"2026-05-11T01:43:25.407203Z","end":"2026-05-11T01:43:25.642558Z","steps":["trace[1539438709] 'agreement among raft nodes before linearized reading' (duration: 235.140633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:25.642846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.779903ms","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-05-11T01:43:25.642923Z","caller":"traceutil/trace.go:171","msg":"trace[1109391898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24057; }","duration":"217.885585ms","start":"2026-05-11T01:43:25.425023Z","end":"2026-05-11T01:43:25.642909Z","steps":["trace[1109391898] 'agreement among raft nodes before linearized reading' (duration: 217.695221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:25.643046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.24014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:43:25.643147Z","caller":"traceutil/trace.go:171","msg":"trace[340633333] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24057; }","duration":"163.419613ms","start":"2026-05-11T01:43:25.479705Z","end":"2026-05-11T01:43:25.643124Z","steps":["trace[340633333] 'agreement among raft nodes before linearized reading' (duration: 163.119097ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:25.746815Z","caller":"traceutil/trace.go:171","msg":"trace[1281868162] transaction","detail":"{read_only:false; response_revision:24058; number_of_response:1; }","duration":"100.598685ms","start":"2026-05-11T01:43:25.646172Z","end":"2026-05-11T01:43:25.746771Z","steps":["trace[1281868162] 'process raft request' (duration: 100.433731ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:25.747506Z","caller":"traceutil/trace.go:171","msg":"trace[252985012] transaction","detail":"{read_only:false; response_revision:24059; number_of_response:1; }","duration":"101.193088ms","start":"2026-05-11T01:43:25.646288Z","end":"2026-05-11T01:43:25.747481Z","steps":["trace[252985012] 'process raft request' (duration: 100.990543ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:25.747807Z","caller":"traceutil/trace.go:171","msg":"trace[669417938] transaction","detail":"{read_only:false; response_revision:24060; number_of_response:1; }","duration":"100.307709ms","start":"2026-05-11T01:43:25.647484Z","end":"2026-05-11T01:43:25.747792Z","steps":["trace[669417938] 'process raft request' (duration: 99.943921ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:31.069224Z","caller":"traceutil/trace.go:171","msg":"trace[992919281] transaction","detail":"{read_only:false; response_revision:24105; number_of_response:1; }","duration":"144.620873ms","start":"2026-05-11T01:43:30.92452Z","end":"2026-05-11T01:43:31.069141Z","steps":["trace[992919281] 'process raft request' (duration: 143.781055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:31.374904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.771082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:43:31.37502Z","caller":"traceutil/trace.go:171","msg":"trace[1784843557] transaction","detail":"{read_only:false; response_revision:24106; number_of_response:1; }","duration":"167.333477ms","start":"2026-05-11T01:43:31.207664Z","end":"2026-05-11T01:43:31.374998Z","steps":["trace[1784843557] 'process raft request' (duration: 56.365701ms)","trace[1784843557] 'compare' (duration: 110.434065ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:31.689893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.151052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:43:31.690035Z","caller":"traceutil/trace.go:171","msg":"trace[1693937210] transaction","detail":"{read_only:false; response_revision:24107; number_of_response:1; }","duration":"270.67839ms","start":"2026-05-11T01:43:31.419338Z","end":"2026-05-11T01:43:31.690016Z","steps":["trace[1693937210] 'process raft request' (duration: 150.250062ms)","trace[1693937210] 'compare' (duration: 119.824175ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:31.891728Z","caller":"traceutil/trace.go:171","msg":"trace[2032339400] linearizableReadLoop","detail":"{readStateIndex:24892; appliedIndex:24891; }","duration":"117.147897ms","start":"2026-05-11T01:43:31.774535Z","end":"2026-05-11T01:43:31.891683Z","steps":["trace[2032339400] 'read index received' (duration: 96.132259ms)","trace[2032339400] 'applied index is now lower than readState.Index' (duration: 21.013488ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:31.891797Z","caller":"traceutil/trace.go:171","msg":"trace[1824619266] transaction","detail":"{read_only:false; response_revision:24109; number_of_response:1; }","duration":"226.448097ms","start":"2026-05-11T01:43:31.66531Z","end":"2026-05-11T01:43:31.891758Z","steps":["trace[1824619266] 'process raft request' (duration: 205.433409ms)","trace[1824619266] 'compare' (duration: 20.66623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:31.892006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.311611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:43:31.892046Z","caller":"traceutil/trace.go:171","msg":"trace[1514016215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24109; }","duration":"117.421233ms","start":"2026-05-11T01:43:31.774612Z","end":"2026-05-11T01:43:31.892034Z","steps":["trace[1514016215] 'agreement among raft nodes before linearized reading' (duration: 117.314501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:31.892126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.864587ms","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":"warn","ts":"2026-05-11T01:43:31.892124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.492919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-11T01:43:31.892148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.596007ms","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-05-11T01:43:31.892213Z","caller":"traceutil/trace.go:171","msg":"trace[1998815419] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24109; }","duration":"112.97794ms","start":"2026-05-11T01:43:31.779211Z","end":"2026-05-11T01:43:31.892189Z","steps":["trace[1998815419] 'agreement among raft nodes before linearized reading' (duration: 112.752635ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:31.89223Z","caller":"traceutil/trace.go:171","msg":"trace[1365935528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24109; }","duration":"112.624852ms","start":"2026-05-11T01:43:31.779576Z","end":"2026-05-11T01:43:31.892201Z","steps":["trace[1365935528] 'agreement among raft nodes before linearized reading' (duration: 112.353986ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:31.89224Z","caller":"traceutil/trace.go:171","msg":"trace[861116536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24109; }","duration":"117.74949ms","start":"2026-05-11T01:43:31.774468Z","end":"2026-05-11T01:43:31.892218Z","steps":["trace[861116536] 'agreement among raft nodes before linearized reading' (duration: 117.424843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:31.892405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.085812ms","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":"warn","ts":"2026-05-11T01:43:31.892397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.549744ms","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-05-11T01:43:31.892437Z","caller":"traceutil/trace.go:171","msg":"trace[81333806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24109; }","duration":"106.136493ms","start":"2026-05-11T01:43:31.786292Z","end":"2026-05-11T01:43:31.892428Z","steps":["trace[81333806] 'agreement among raft nodes before linearized reading' (duration: 106.03293ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:31.89245Z","caller":"traceutil/trace.go:171","msg":"trace[299375480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24109; }","duration":"115.658577ms","start":"2026-05-11T01:43:31.776777Z","end":"2026-05-11T01:43:31.892436Z","steps":["trace[299375480] 'agreement among raft nodes before linearized reading' (duration: 115.452552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:32.226915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.99276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:43:32.227238Z","caller":"traceutil/trace.go:171","msg":"trace[1784029840] transaction","detail":"{read_only:false; response_revision:24110; number_of_response:1; }","duration":"328.777008ms","start":"2026-05-11T01:43:31.898419Z","end":"2026-05-11T01:43:32.227196Z","steps":["trace[1784029840] 'process raft request' (duration: 153.38665ms)","trace[1784029840] 'compare' (duration: 174.806866ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:32.227402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:31.898393Z","time spent":"328.923092ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:43:32.227773Z","caller":"traceutil/trace.go:171","msg":"trace[479339234] transaction","detail":"{read_only:false; response_revision:24112; number_of_response:1; }","duration":"327.043261ms","start":"2026-05-11T01:43:31.900717Z","end":"2026-05-11T01:43:32.22776Z","steps":["trace[479339234] 'process raft request' (duration: 326.427478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:32.227844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:31.900656Z","time spent":"327.154763ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:43:32.227937Z","caller":"traceutil/trace.go:171","msg":"trace[1618349646] transaction","detail":"{read_only:false; response_revision:24113; number_of_response:1; }","duration":"320.633395ms","start":"2026-05-11T01:43:31.907264Z","end":"2026-05-11T01:43:32.227897Z","steps":["trace[1618349646] 'process raft request' (duration: 319.92372ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:32.228121Z","caller":"traceutil/trace.go:171","msg":"trace[1653337973] transaction","detail":"{read_only:false; response_revision:24114; number_of_response:1; }","duration":"320.078795ms","start":"2026-05-11T01:43:31.908029Z","end":"2026-05-11T01:43:32.228107Z","steps":["trace[1653337973] 'process raft request' (duration: 319.196535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:32.228142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:31.907249Z","time spent":"320.797549ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:43:32.22819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:31.908014Z","time spent":"320.144696ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:43:32.227991Z","caller":"traceutil/trace.go:171","msg":"trace[1680844514] transaction","detail":"{read_only:false; response_revision:24111; number_of_response:1; }","duration":"329.486045ms","start":"2026-05-11T01:43:31.898495Z","end":"2026-05-11T01:43:32.227981Z","steps":["trace[1680844514] 'process raft request' (duration: 328.539624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:32.228406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:31.898464Z","time spent":"329.911063ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:43:32.50005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.765256ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-11T01:43:32.500168Z","caller":"traceutil/trace.go:171","msg":"trace[2006158942] linearizableReadLoop","detail":"{readStateIndex:24897; appliedIndex:24892; }","duration":"532.576273ms","start":"2026-05-11T01:43:31.967571Z","end":"2026-05-11T01:43:32.500147Z","steps":["trace[2006158942] 'read index received' (duration: 84.214845ms)","trace[2006158942] 'applied index is now lower than readState.Index' (duration: 448.359628ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:32.500468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.87102ms","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":"info","ts":"2026-05-11T01:43:32.500517Z","caller":"traceutil/trace.go:171","msg":"trace[1328117806] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24114; }","duration":"532.997203ms","start":"2026-05-11T01:43:31.967504Z","end":"2026-05-11T01:43:32.500501Z","steps":["trace[1328117806] 'agreement among raft nodes before linearized reading' (duration: 532.892381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:32.500561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:31.967475Z","time spent":"533.076184ms","remote":"127.0.0.1:48072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:43:32.500676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.909873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:43:32.500755Z","caller":"traceutil/trace.go:171","msg":"trace[602454216] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:24114; }","duration":"329.039105ms","start":"2026-05-11T01:43:32.171698Z","end":"2026-05-11T01:43:32.500737Z","steps":["trace[602454216] 'agreement among raft nodes before linearized reading' (duration: 328.842771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:32.500803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:32.171675Z","time spent":"329.116557ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":38,"response size":32,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:43:32.500905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.326199ms","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-05-11T01:43:32.50096Z","caller":"traceutil/trace.go:171","msg":"trace[63292436] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24114; }","duration":"221.428251ms","start":"2026-05-11T01:43:32.27952Z","end":"2026-05-11T01:43:32.500948Z","steps":["trace[63292436] 'agreement among raft nodes before linearized reading' (duration: 221.217077ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:32.955725Z","caller":"traceutil/trace.go:171","msg":"trace[93581915] linearizableReadLoop","detail":"{readStateIndex:24899; appliedIndex:24898; }","duration":"185.588136ms","start":"2026-05-11T01:43:32.770097Z","end":"2026-05-11T01:43:32.955685Z","steps":["trace[93581915] 'read index received' (duration: 185.414682ms)","trace[93581915] 'applied index is now lower than readState.Index' (duration: 171.934µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:32.955921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.802451ms","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-05-11T01:43:32.955896Z","caller":"traceutil/trace.go:171","msg":"trace[1446842557] transaction","detail":"{read_only:false; response_revision:24115; number_of_response:1; }","duration":"190.366159ms","start":"2026-05-11T01:43:32.765449Z","end":"2026-05-11T01:43:32.955816Z","steps":["trace[1446842557] 'process raft request' (duration: 190.024462ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:32.955976Z","caller":"traceutil/trace.go:171","msg":"trace[799923297] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24115; }","duration":"185.925734ms","start":"2026-05-11T01:43:32.770033Z","end":"2026-05-11T01:43:32.955958Z","steps":["trace[799923297] 'agreement among raft nodes before linearized reading' (duration: 185.818762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:32.956121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.803712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:43:32.956241Z","caller":"traceutil/trace.go:171","msg":"trace[1560335845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24115; }","duration":"178.979204ms","start":"2026-05-11T01:43:32.777238Z","end":"2026-05-11T01:43:32.956217Z","steps":["trace[1560335845] 'agreement among raft nodes before linearized reading' (duration: 178.78217ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:33.220258Z","caller":"traceutil/trace.go:171","msg":"trace[1204147226] transaction","detail":"{read_only:false; response_revision:24116; number_of_response:1; }","duration":"260.207657ms","start":"2026-05-11T01:43:32.96001Z","end":"2026-05-11T01:43:33.220218Z","steps":["trace[1204147226] 'process raft request' (duration: 218.179951ms)","trace[1204147226] 'compare' (duration: 41.806221ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:33.233583Z","caller":"traceutil/trace.go:171","msg":"trace[1238064019] linearizableReadLoop","detail":"{readStateIndex:24901; appliedIndex:24899; }","duration":"239.150158ms","start":"2026-05-11T01:43:32.994417Z","end":"2026-05-11T01:43:33.233567Z","steps":["trace[1238064019] 'read index received' (duration: 183.765028ms)","trace[1238064019] 'applied index is now lower than readState.Index' (duration: 55.38409ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:33.233628Z","caller":"traceutil/trace.go:171","msg":"trace[1538209392] transaction","detail":"{read_only:false; response_revision:24117; number_of_response:1; }","duration":"261.28553ms","start":"2026-05-11T01:43:32.972314Z","end":"2026-05-11T01:43:33.2336Z","steps":["trace[1538209392] 'process raft request' (duration: 261.143547ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:33.233672Z","caller":"traceutil/trace.go:171","msg":"trace[2047946938] transaction","detail":"{read_only:false; response_revision:24118; number_of_response:1; }","duration":"151.376228ms","start":"2026-05-11T01:43:33.082289Z","end":"2026-05-11T01:43:33.233665Z","steps":["trace[2047946938] 'process raft request' (duration: 151.249554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:33.233861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.415394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:43:33.23389Z","caller":"traceutil/trace.go:171","msg":"trace[485232157] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24118; }","duration":"239.502217ms","start":"2026-05-11T01:43:32.994378Z","end":"2026-05-11T01:43:33.233881Z","steps":["trace[485232157] 'agreement among raft nodes before linearized reading' (duration: 239.421235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:33.234136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.343574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T01:43:33.234179Z","caller":"traceutil/trace.go:171","msg":"trace[1198216358] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24118; }","duration":"216.420955ms","start":"2026-05-11T01:43:33.017748Z","end":"2026-05-11T01:43:33.234169Z","steps":["trace[1198216358] 'agreement among raft nodes before linearized reading' (duration: 216.21393ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:33.948783Z","caller":"traceutil/trace.go:171","msg":"trace[281908601] transaction","detail":"{read_only:false; response_revision:24121; number_of_response:1; }","duration":"251.979132ms","start":"2026-05-11T01:43:33.696778Z","end":"2026-05-11T01:43:33.948757Z","steps":["trace[281908601] 'process raft request' (duration: 251.796748ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:34.056396Z","caller":"traceutil/trace.go:171","msg":"trace[323234412] transaction","detail":"{read_only:false; response_revision:24122; number_of_response:1; }","duration":"348.354006ms","start":"2026-05-11T01:43:33.707999Z","end":"2026-05-11T01:43:34.056353Z","steps":["trace[323234412] 'process raft request' (duration: 347.195321ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:34.056427Z","caller":"traceutil/trace.go:171","msg":"trace[679010573] transaction","detail":"{read_only:false; response_revision:24123; number_of_response:1; }","duration":"150.987649ms","start":"2026-05-11T01:43:33.905415Z","end":"2026-05-11T01:43:34.056403Z","steps":["trace[679010573] 'process raft request' (duration: 150.813415ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:34.056618Z","caller":"traceutil/trace.go:171","msg":"trace[805672779] linearizableReadLoop","detail":"{readStateIndex:24906; appliedIndex:24904; }","duration":"263.058968ms","start":"2026-05-11T01:43:33.793543Z","end":"2026-05-11T01:43:34.056602Z","steps":["trace[805672779] 'read index received' (duration: 155.106257ms)","trace[805672779] 'applied index is now lower than readState.Index' (duration: 107.950971ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:34.056858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:33.707982Z","time spent":"348.498519ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:43:34.056954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.384404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:43:34.057113Z","caller":"traceutil/trace.go:171","msg":"trace[1906999582] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24124; }","duration":"263.599839ms","start":"2026-05-11T01:43:33.793493Z","end":"2026-05-11T01:43:34.057093Z","steps":["trace[1906999582] 'agreement among raft nodes before linearized reading' (duration: 263.341354ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:34.057004Z","caller":"traceutil/trace.go:171","msg":"trace[845969838] transaction","detail":"{read_only:false; response_revision:24124; number_of_response:1; }","duration":"148.921745ms","start":"2026-05-11T01:43:33.908062Z","end":"2026-05-11T01:43:34.056984Z","steps":["trace[845969838] 'process raft request' (duration: 148.280161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:34.344575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.419392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:43:34.345173Z","caller":"traceutil/trace.go:171","msg":"trace[2147174754] linearizableReadLoop","detail":"{readStateIndex:24909; appliedIndex:24908; }","duration":"120.989979ms","start":"2026-05-11T01:43:34.224163Z","end":"2026-05-11T01:43:34.345153Z","steps":["trace[2147174754] 'read index received' (duration: 18.791201ms)","trace[2147174754] 'applied index is now lower than readState.Index' (duration: 102.196508ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:34.345257Z","caller":"traceutil/trace.go:171","msg":"trace[580471532] transaction","detail":"{read_only:false; response_revision:24125; number_of_response:1; }","duration":"203.264003ms","start":"2026-05-11T01:43:34.141945Z","end":"2026-05-11T01:43:34.345209Z","steps":["trace[580471532] 'process raft request' (duration: 101.064444ms)","trace[580471532] 'compare' (duration: 101.182777ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:34.345372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.199594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:43:34.345426Z","caller":"traceutil/trace.go:171","msg":"trace[1551672651] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:24125; }","duration":"121.282525ms","start":"2026-05-11T01:43:34.224129Z","end":"2026-05-11T01:43:34.345412Z","steps":["trace[1551672651] 'agreement among raft nodes before linearized reading' (duration: 121.158462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:34.345503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.110217ms","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-05-11T01:43:34.345568Z","caller":"traceutil/trace.go:171","msg":"trace[644075452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24125; }","duration":"110.2302ms","start":"2026-05-11T01:43:34.235323Z","end":"2026-05-11T01:43:34.345554Z","steps":["trace[644075452] 'agreement among raft nodes before linearized reading' (duration: 110.015815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:34.345767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.334402ms","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-05-11T01:43:34.345795Z","caller":"traceutil/trace.go:171","msg":"trace[1649362354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24125; }","duration":"110.373633ms","start":"2026-05-11T01:43:34.235413Z","end":"2026-05-11T01:43:34.345787Z","steps":["trace[1649362354] 'agreement among raft nodes before linearized reading' (duration: 110.304371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:34.345838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.260091ms","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-05-11T01:43:34.34592Z","caller":"traceutil/trace.go:171","msg":"trace[1488213535] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24125; }","duration":"110.366013ms","start":"2026-05-11T01:43:34.235537Z","end":"2026-05-11T01:43:34.345903Z","steps":["trace[1488213535] 'agreement among raft nodes before linearized reading' (duration: 110.119597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:34.345981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.570848ms","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-05-11T01:43:34.346008Z","caller":"traceutil/trace.go:171","msg":"trace[1662407904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24125; }","duration":"110.614349ms","start":"2026-05-11T01:43:34.235387Z","end":"2026-05-11T01:43:34.346002Z","steps":["trace[1662407904] 'agreement among raft nodes before linearized reading' (duration: 110.536598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:34.346162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.279232ms","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-05-11T01:43:34.346187Z","caller":"traceutil/trace.go:171","msg":"trace[359895326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24125; }","duration":"110.342303ms","start":"2026-05-11T01:43:34.235838Z","end":"2026-05-11T01:43:34.346181Z","steps":["trace[359895326] 'agreement among raft nodes before linearized reading' (duration: 110.280462ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:34.541764Z","caller":"traceutil/trace.go:171","msg":"trace[411641058] transaction","detail":"{read_only:false; response_revision:24126; number_of_response:1; }","duration":"191.736837ms","start":"2026-05-11T01:43:34.349999Z","end":"2026-05-11T01:43:34.541736Z","steps":["trace[411641058] 'process raft request' (duration: 191.545433ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:34.543636Z","caller":"traceutil/trace.go:171","msg":"trace[1775805851] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"193.212169ms","start":"2026-05-11T01:43:34.35039Z","end":"2026-05-11T01:43:34.543602Z","steps":["trace[1775805851] 'process raft request' (duration: 193.105897ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:34.54368Z","caller":"traceutil/trace.go:171","msg":"trace[331681241] transaction","detail":"{read_only:false; response_revision:24127; number_of_response:1; }","duration":"193.317661ms","start":"2026-05-11T01:43:34.350323Z","end":"2026-05-11T01:43:34.543641Z","steps":["trace[331681241] 'process raft request' (duration: 193.021915ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:34.544043Z","caller":"traceutil/trace.go:171","msg":"trace[1721059155] transaction","detail":"{read_only:false; response_revision:24130; number_of_response:1; }","duration":"193.072586ms","start":"2026-05-11T01:43:34.350942Z","end":"2026-05-11T01:43:34.544014Z","steps":["trace[1721059155] 'process raft request' (duration: 192.892402ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:34.544026Z","caller":"traceutil/trace.go:171","msg":"trace[1533254199] linearizableReadLoop","detail":"{readStateIndex:24914; appliedIndex:24909; }","duration":"186.430524ms","start":"2026-05-11T01:43:34.357563Z","end":"2026-05-11T01:43:34.543993Z","steps":["trace[1533254199] 'read index received' (duration: 184.069334ms)","trace[1533254199] 'applied index is now lower than readState.Index' (duration: 2.35937ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:34.544035Z","caller":"traceutil/trace.go:171","msg":"trace[1868387748] transaction","detail":"{read_only:false; response_revision:24129; number_of_response:1; }","duration":"193.062485ms","start":"2026-05-11T01:43:34.350874Z","end":"2026-05-11T01:43:34.543936Z","steps":["trace[1868387748] 'process raft request' (duration: 192.674787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:34.54434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.747251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-11T01:43:34.544391Z","caller":"traceutil/trace.go:171","msg":"trace[1340663980] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24130; }","duration":"186.845062ms","start":"2026-05-11T01:43:34.357533Z","end":"2026-05-11T01:43:34.544378Z","steps":["trace[1340663980] 'agreement among raft nodes before linearized reading' (duration: 186.563067ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:36.094488Z","caller":"traceutil/trace.go:171","msg":"trace[2087876771] transaction","detail":"{read_only:false; response_revision:24136; number_of_response:1; }","duration":"139.014294ms","start":"2026-05-11T01:43:35.955448Z","end":"2026-05-11T01:43:36.094462Z","steps":["trace[2087876771] 'process raft request' (duration: 138.81458ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:51.421897Z","caller":"traceutil/trace.go:171","msg":"trace[2124924159] transaction","detail":"{read_only:false; response_revision:24302; number_of_response:1; }","duration":"504.064076ms","start":"2026-05-11T01:43:50.917807Z","end":"2026-05-11T01:43:51.421871Z","steps":["trace[2124924159] 'process raft request' (duration: 503.839652ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:51.42192Z","caller":"traceutil/trace.go:171","msg":"trace[1291432311] linearizableReadLoop","detail":"{readStateIndex:25089; appliedIndex:25089; }","duration":"484.683423ms","start":"2026-05-11T01:43:50.937157Z","end":"2026-05-11T01:43:51.421841Z","steps":["trace[1291432311] 'read index received' (duration: 484.667352ms)","trace[1291432311] 'applied index is now lower than readState.Index' (duration: 13.571µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:51.42205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:50.917787Z","time spent":"504.183468ms","remote":"127.0.0.1:52784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1319,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:43:51.422192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.957358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:43:51.422287Z","caller":"traceutil/trace.go:171","msg":"trace[2075197014] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:24302; }","duration":"485.152583ms","start":"2026-05-11T01:43:50.937113Z","end":"2026-05-11T01:43:51.422266Z","steps":["trace[2075197014] 'agreement among raft nodes before linearized reading' (duration: 484.890017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:51.422351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:50.937095Z","time spent":"485.242254ms","remote":"127.0.0.1:60614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":30,"response size":32,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:43:51.921282Z","caller":"traceutil/trace.go:171","msg":"trace[1051912151] transaction","detail":"{read_only:false; response_revision:24303; number_of_response:1; }","duration":"900.194231ms","start":"2026-05-11T01:43:51.021033Z","end":"2026-05-11T01:43:51.921227Z","steps":["trace[1051912151] 'process raft request' (duration: 876.429654ms)","trace[1051912151] 'compare' (duration: 23.397099ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:51.921425Z","caller":"traceutil/trace.go:171","msg":"trace[1289920412] linearizableReadLoop","detail":"{readStateIndex:25092; appliedIndex:25089; }","duration":"499.391046ms","start":"2026-05-11T01:43:51.422002Z","end":"2026-05-11T01:43:51.921393Z","steps":["trace[1289920412] 'read index received' (duration: 475.487087ms)","trace[1289920412] 'applied index is now lower than readState.Index' (duration: 23.900659ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:51.921476Z","caller":"traceutil/trace.go:171","msg":"trace[919565343] transaction","detail":"{read_only:false; response_revision:24305; number_of_response:1; }","duration":"609.744028ms","start":"2026-05-11T01:43:51.311682Z","end":"2026-05-11T01:43:51.921426Z","steps":["trace[919565343] 'process raft request' (duration: 609.541114ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:51.921304Z","caller":"traceutil/trace.go:171","msg":"trace[2057415106] transaction","detail":"{read_only:false; response_revision:24304; number_of_response:1; }","duration":"614.96995ms","start":"2026-05-11T01:43:51.306301Z","end":"2026-05-11T01:43:51.921271Z","steps":["trace[2057415106] 'process raft request' (duration: 614.812626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:51.921545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.129435ms","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-05-11T01:43:51.921589Z","caller":"traceutil/trace.go:171","msg":"trace[1695558453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24305; }","duration":"608.170436ms","start":"2026-05-11T01:43:51.313394Z","end":"2026-05-11T01:43:51.921565Z","steps":["trace[1695558453] 'agreement among raft nodes before linearized reading' (duration: 608.025233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:51.921572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.021009Z","time spent":"900.396234ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:43:51.921551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.242526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:43:51.92164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.306278Z","time spent":"615.276555ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:43:51.921664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.311666Z","time spent":"609.886911ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:43:51.921674Z","caller":"traceutil/trace.go:171","msg":"trace[1831722906] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:24305; }","duration":"888.40823ms","start":"2026-05-11T01:43:51.033224Z","end":"2026-05-11T01:43:51.921632Z","steps":["trace[1831722906] 'agreement among raft nodes before linearized reading' (duration: 888.225515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:51.921738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.033202Z","time spent":"888.520943ms","remote":"127.0.0.1:48080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:43:51.921609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.313369Z","time spent":"608.237008ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T01:43:51.922385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.478096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2213913"} {"level":"info","ts":"2026-05-11T01:43:51.922436Z","caller":"traceutil/trace.go:171","msg":"trace[552450154] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:24305; }","duration":"945.583549ms","start":"2026-05-11T01:43:50.976839Z","end":"2026-05-11T01:43:51.922423Z","steps":["trace[552450154] 'agreement among raft nodes before linearized reading' (duration: 944.672979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:51.922466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:50.976821Z","time spent":"945.636119ms","remote":"127.0.0.1:41616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2213937,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-05-11T01:43:52.170625Z","caller":"traceutil/trace.go:171","msg":"trace[1400740046] transaction","detail":"{read_only:false; response_revision:24306; number_of_response:1; }","duration":"739.844533ms","start":"2026-05-11T01:43:51.430734Z","end":"2026-05-11T01:43:52.170578Z","steps":["trace[1400740046] 'process raft request' (duration: 692.962874ms)","trace[1400740046] 'compare' (duration: 45.887688ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:52.170816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.430706Z","time spent":"740.007556ms","remote":"127.0.0.1:52560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2228,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T01:43:52.174694Z","caller":"traceutil/trace.go:171","msg":"trace[36251270] transaction","detail":"{read_only:false; response_revision:24307; number_of_response:1; }","duration":"644.270425ms","start":"2026-05-11T01:43:51.530396Z","end":"2026-05-11T01:43:52.174667Z","steps":["trace[36251270] 'process raft request' (duration: 643.902937ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:52.174762Z","caller":"traceutil/trace.go:171","msg":"trace[147679844] linearizableReadLoop","detail":"{readStateIndex:25095; appliedIndex:25092; }","duration":"253.2645ms","start":"2026-05-11T01:43:51.921482Z","end":"2026-05-11T01:43:52.174747Z","steps":["trace[147679844] 'read index received' (duration: 202.231272ms)","trace[147679844] 'applied index is now lower than readState.Index' (duration: 51.032148ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:52.174858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.530377Z","time spent":"644.391067ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:43:52.174948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.480262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-060fdca0-974c-41c2-a7a0-2ae6d6ee5dd6\" ","response":"range_response_count:1 size:3643"} {"level":"warn","ts":"2026-05-11T01:43:52.17494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.515946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:43:52.175037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.425464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:43:52.174707Z","caller":"traceutil/trace.go:171","msg":"trace[1763716177] transaction","detail":"{read_only:false; response_revision:24308; number_of_response:1; }","duration":"248.974358ms","start":"2026-05-11T01:43:51.925714Z","end":"2026-05-11T01:43:52.174688Z","steps":["trace[1763716177] 'process raft request' (duration: 248.885206ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:52.175072Z","caller":"traceutil/trace.go:171","msg":"trace[1919195600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24308; }","duration":"398.879303ms","start":"2026-05-11T01:43:51.776163Z","end":"2026-05-11T01:43:52.175042Z","steps":["trace[1919195600] 'agreement among raft nodes before linearized reading' (duration: 398.649398ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:52.175112Z","caller":"traceutil/trace.go:171","msg":"trace[1774712284] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:24308; }","duration":"747.538296ms","start":"2026-05-11T01:43:51.427556Z","end":"2026-05-11T01:43:52.175094Z","steps":["trace[1774712284] 'agreement among raft nodes before linearized reading' (duration: 747.419544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.175147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.427542Z","time spent":"747.597508ms","remote":"127.0.0.1:33592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:43:52.175154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.776141Z","time spent":"398.998896ms","remote":"127.0.0.1:41510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T01:43:52.174989Z","caller":"traceutil/trace.go:171","msg":"trace[255747434] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-060fdca0-974c-41c2-a7a0-2ae6d6ee5dd6; range_end:; response_count:1; response_revision:24308; }","duration":"699.568624ms","start":"2026-05-11T01:43:51.475409Z","end":"2026-05-11T01:43:52.174977Z","steps":["trace[255747434] 'agreement among raft nodes before linearized reading' (duration: 699.426271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.175216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.475386Z","time spent":"699.819279ms","remote":"127.0.0.1:41714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-060fdca0-974c-41c2-a7a0-2ae6d6ee5dd6\" "} {"level":"warn","ts":"2026-05-11T01:43:52.263276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.61235ms","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-05-11T01:43:52.263357Z","caller":"traceutil/trace.go:171","msg":"trace[2069307380] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24309; }","duration":"263.759943ms","start":"2026-05-11T01:43:51.999573Z","end":"2026-05-11T01:43:52.263333Z","steps":["trace[2069307380] 'agreement among raft nodes before linearized reading' (duration: 263.62397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.263471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.155789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"info","ts":"2026-05-11T01:43:52.263616Z","caller":"traceutil/trace.go:171","msg":"trace[1059753242] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:24309; }","duration":"307.307012ms","start":"2026-05-11T01:43:51.956267Z","end":"2026-05-11T01:43:52.263574Z","steps":["trace[1059753242] 'agreement among raft nodes before linearized reading' (duration: 306.977054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.263642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.545892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:43:52.263703Z","caller":"traceutil/trace.go:171","msg":"trace[317861018] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24309; }","duration":"326.625273ms","start":"2026-05-11T01:43:51.937058Z","end":"2026-05-11T01:43:52.263684Z","steps":["trace[317861018] 'agreement among raft nodes before linearized reading' (duration: 326.524531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.263707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.956209Z","time spent":"307.477495ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1846,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-11T01:43:52.263762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:51.93705Z","time spent":"326.689324ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":30,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:43:52.552494Z","caller":"traceutil/trace.go:171","msg":"trace[21803720] linearizableReadLoop","detail":"{readStateIndex:25099; appliedIndex:25097; }","duration":"289.271097ms","start":"2026-05-11T01:43:52.263185Z","end":"2026-05-11T01:43:52.552456Z","steps":["trace[21803720] 'read index received' (duration: 288.802388ms)","trace[21803720] 'applied index is now lower than readState.Index' (duration: 466.329µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:52.552997Z","caller":"traceutil/trace.go:171","msg":"trace[1795015365] transaction","detail":"{read_only:false; response_revision:24310; number_of_response:1; }","duration":"373.334988ms","start":"2026-05-11T01:43:52.179647Z","end":"2026-05-11T01:43:52.552982Z","steps":["trace[1795015365] 'process raft request' (duration: 372.497191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.553165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:52.179638Z","time spent":"373.409461ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:43:52.553472Z","caller":"traceutil/trace.go:171","msg":"trace[1453800982] transaction","detail":"{read_only:false; response_revision:24311; number_of_response:1; }","duration":"369.768763ms","start":"2026-05-11T01:43:52.183691Z","end":"2026-05-11T01:43:52.553459Z","steps":["trace[1453800982] 'process raft request' (duration: 368.689561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.553544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:52.183684Z","time spent":"369.822524ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:43:52.553746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.250093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:43:52.553792Z","caller":"traceutil/trace.go:171","msg":"trace[1069129710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24311; }","duration":"377.317124ms","start":"2026-05-11T01:43:52.176464Z","end":"2026-05-11T01:43:52.553781Z","steps":["trace[1069129710] 'agreement among raft nodes before linearized reading' (duration: 377.224782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.553823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:43:52.176447Z","time spent":"377.370676ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T01:43:52.554152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.800146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-05-11T01:43:52.554195Z","caller":"traceutil/trace.go:171","msg":"trace[111930436] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:24311; }","duration":"258.880058ms","start":"2026-05-11T01:43:52.295302Z","end":"2026-05-11T01:43:52.554182Z","steps":["trace[111930436] 'agreement among raft nodes before linearized reading' (duration: 258.717585ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:52.827095Z","caller":"traceutil/trace.go:171","msg":"trace[1698072095] linearizableReadLoop","detail":"{readStateIndex:25100; appliedIndex:25099; }","duration":"253.984695ms","start":"2026-05-11T01:43:52.573088Z","end":"2026-05-11T01:43:52.827073Z","steps":["trace[1698072095] 'read index received' (duration: 167.192674ms)","trace[1698072095] 'applied index is now lower than readState.Index' (duration: 86.790871ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:52.827275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.158658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:43:52.82737Z","caller":"traceutil/trace.go:171","msg":"trace[1360110767] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:24312; }","duration":"254.265511ms","start":"2026-05-11T01:43:52.573083Z","end":"2026-05-11T01:43:52.827348Z","steps":["trace[1360110767] 'agreement among raft nodes before linearized reading' (duration: 254.112698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.827409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.584615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-05-11T01:43:52.827436Z","caller":"traceutil/trace.go:171","msg":"trace[315101508] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24312; }","duration":"252.637155ms","start":"2026-05-11T01:43:52.574791Z","end":"2026-05-11T01:43:52.827429Z","steps":["trace[315101508] 'agreement among raft nodes before linearized reading' (duration: 252.512083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.827588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.972048ms","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-05-11T01:43:52.827609Z","caller":"traceutil/trace.go:171","msg":"trace[1484932518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24312; }","duration":"165.040199ms","start":"2026-05-11T01:43:52.662563Z","end":"2026-05-11T01:43:52.827603Z","steps":["trace[1484932518] 'agreement among raft nodes before linearized reading' (duration: 164.986228ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:52.827603Z","caller":"traceutil/trace.go:171","msg":"trace[1805933953] transaction","detail":"{read_only:false; response_revision:24312; number_of_response:1; }","duration":"255.912696ms","start":"2026-05-11T01:43:52.571675Z","end":"2026-05-11T01:43:52.827587Z","steps":["trace[1805933953] 'process raft request' (duration: 168.682486ms)","trace[1805933953] 'compare' (duration: 86.481964ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:52.82772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.873716ms","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-05-11T01:43:52.827738Z","caller":"traceutil/trace.go:171","msg":"trace[1425078874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24312; }","duration":"165.909087ms","start":"2026-05-11T01:43:52.661824Z","end":"2026-05-11T01:43:52.827734Z","steps":["trace[1425078874] 'agreement among raft nodes before linearized reading' (duration: 165.861026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.827826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.755716ms","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-05-11T01:43:52.827845Z","caller":"traceutil/trace.go:171","msg":"trace[203169848] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24312; }","duration":"166.796247ms","start":"2026-05-11T01:43:52.661042Z","end":"2026-05-11T01:43:52.827838Z","steps":["trace[203169848] 'agreement among raft nodes before linearized reading' (duration: 166.749656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.827963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.412825ms","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-05-11T01:43:52.827981Z","caller":"traceutil/trace.go:171","msg":"trace[704230547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24312; }","duration":"171.454415ms","start":"2026-05-11T01:43:52.656522Z","end":"2026-05-11T01:43:52.827976Z","steps":["trace[704230547] 'agreement among raft nodes before linearized reading' (duration: 171.407214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:52.828177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.185417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-11T01:43:52.828212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.017465ms","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-05-11T01:43:52.828232Z","caller":"traceutil/trace.go:171","msg":"trace[2579281] 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:24312; }","duration":"185.039295ms","start":"2026-05-11T01:43:52.643186Z","end":"2026-05-11T01:43:52.828225Z","steps":["trace[2579281] 'agreement among raft nodes before linearized reading' (duration: 185.002154ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:52.828212Z","caller":"traceutil/trace.go:171","msg":"trace[927452255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24312; }","duration":"184.220448ms","start":"2026-05-11T01:43:52.643982Z","end":"2026-05-11T01:43:52.828202Z","steps":["trace[927452255] 'agreement among raft nodes before linearized reading' (duration: 184.055824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:53.150696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.750288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:43:53.150809Z","caller":"traceutil/trace.go:171","msg":"trace[1712946150] linearizableReadLoop","detail":"{readStateIndex:25107; appliedIndex:25106; }","duration":"247.030456ms","start":"2026-05-11T01:43:52.90376Z","end":"2026-05-11T01:43:53.150791Z","steps":["trace[1712946150] 'read index received' (duration: 146.047794ms)","trace[1712946150] 'applied index is now lower than readState.Index' (duration: 100.981402ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:53.150911Z","caller":"traceutil/trace.go:171","msg":"trace[68786584] transaction","detail":"{read_only:false; response_revision:24319; number_of_response:1; }","duration":"247.486896ms","start":"2026-05-11T01:43:52.90341Z","end":"2026-05-11T01:43:53.150897Z","steps":["trace[68786584] 'process raft request' (duration: 146.438692ms)","trace[68786584] 'compare' (duration: 100.514242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:53.151273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.507036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:43:53.151309Z","caller":"traceutil/trace.go:171","msg":"trace[926922469] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24319; }","duration":"247.566967ms","start":"2026-05-11T01:43:52.90373Z","end":"2026-05-11T01:43:53.151296Z","steps":["trace[926922469] 'agreement among raft nodes before linearized reading' (duration: 247.493526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:53.151422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.788936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-11T01:43:53.151437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.031574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112177"} {"level":"info","ts":"2026-05-11T01:43:53.151496Z","caller":"traceutil/trace.go:171","msg":"trace[2071533265] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24319; }","duration":"197.91113ms","start":"2026-05-11T01:43:52.953566Z","end":"2026-05-11T01:43:53.151477Z","steps":["trace[2071533265] 'agreement among raft nodes before linearized reading' (duration: 197.654723ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:53.151517Z","caller":"traceutil/trace.go:171","msg":"trace[1104886599] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:24319; }","duration":"245.145566ms","start":"2026-05-11T01:43:52.906353Z","end":"2026-05-11T01:43:53.151498Z","steps":["trace[1104886599] 'agreement among raft nodes before linearized reading' (duration: 244.651335ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:53.409421Z","caller":"traceutil/trace.go:171","msg":"trace[2021745656] linearizableReadLoop","detail":"{readStateIndex:25109; appliedIndex:25108; }","duration":"229.731567ms","start":"2026-05-11T01:43:53.179663Z","end":"2026-05-11T01:43:53.409395Z","steps":["trace[2021745656] 'read index received' (duration: 186.434453ms)","trace[2021745656] 'applied index is now lower than readState.Index' (duration: 43.295984ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:53.409723Z","caller":"traceutil/trace.go:171","msg":"trace[1788158047] transaction","detail":"{read_only:false; response_revision:24321; number_of_response:1; }","duration":"244.430872ms","start":"2026-05-11T01:43:53.165267Z","end":"2026-05-11T01:43:53.409698Z","steps":["trace[1788158047] 'process raft request' (duration: 200.791561ms)","trace[1788158047] 'compare' (duration: 42.968616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:53.410273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.586006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-060fdca0-974c-41c2-a7a0-2ae6d6ee5dd6\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-11T01:43:53.41036Z","caller":"traceutil/trace.go:171","msg":"trace[1217276905] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-060fdca0-974c-41c2-a7a0-2ae6d6ee5dd6; range_end:; response_count:1; response_revision:24321; }","duration":"230.723478ms","start":"2026-05-11T01:43:53.179618Z","end":"2026-05-11T01:43:53.410341Z","steps":["trace[1217276905] 'agreement among raft nodes before linearized reading' (duration: 230.424522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:53.410747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.974501ms","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-05-11T01:43:53.41078Z","caller":"traceutil/trace.go:171","msg":"trace[1228085095] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24321; }","duration":"161.054343ms","start":"2026-05-11T01:43:53.249718Z","end":"2026-05-11T01:43:53.410772Z","steps":["trace[1228085095] 'agreement among raft nodes before linearized reading' (duration: 160.994062ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:53.72094Z","caller":"traceutil/trace.go:171","msg":"trace[233907886] transaction","detail":"{read_only:false; response_revision:24322; number_of_response:1; }","duration":"199.922453ms","start":"2026-05-11T01:43:53.52099Z","end":"2026-05-11T01:43:53.720913Z","steps":["trace[233907886] 'process raft request' (duration: 199.748189ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:54.190219Z","caller":"traceutil/trace.go:171","msg":"trace[1441392593] transaction","detail":"{read_only:false; response_revision:24323; number_of_response:1; }","duration":"263.040728ms","start":"2026-05-11T01:43:53.92716Z","end":"2026-05-11T01:43:54.190201Z","steps":["trace[1441392593] 'process raft request' (duration: 262.903235ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:54.218845Z","caller":"traceutil/trace.go:171","msg":"trace[545090664] transaction","detail":"{read_only:false; response_revision:24324; number_of_response:1; }","duration":"288.402999ms","start":"2026-05-11T01:43:53.930415Z","end":"2026-05-11T01:43:54.218817Z","steps":["trace[545090664] 'process raft request' (duration: 288.206674ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:54.219163Z","caller":"traceutil/trace.go:171","msg":"trace[1024821311] transaction","detail":"{read_only:false; response_revision:24325; number_of_response:1; }","duration":"288.593263ms","start":"2026-05-11T01:43:53.930544Z","end":"2026-05-11T01:43:54.219137Z","steps":["trace[1024821311] 'process raft request' (duration: 288.228085ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:54.219234Z","caller":"traceutil/trace.go:171","msg":"trace[1261941671] linearizableReadLoop","detail":"{readStateIndex:25113; appliedIndex:25110; }","duration":"282.912201ms","start":"2026-05-11T01:43:53.936294Z","end":"2026-05-11T01:43:54.219206Z","steps":["trace[1261941671] 'read index received' (duration: 253.882482ms)","trace[1261941671] 'applied index is now lower than readState.Index' (duration: 29.028859ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:54.21925Z","caller":"traceutil/trace.go:171","msg":"trace[1268667874] transaction","detail":"{read_only:false; response_revision:24326; number_of_response:1; }","duration":"129.570283ms","start":"2026-05-11T01:43:54.08966Z","end":"2026-05-11T01:43:54.21923Z","steps":["trace[1268667874] 'process raft request' (duration: 129.381689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:54.219432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.131285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-05-11T01:43:54.219463Z","caller":"traceutil/trace.go:171","msg":"trace[1764316805] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:24327; }","duration":"283.203987ms","start":"2026-05-11T01:43:53.936249Z","end":"2026-05-11T01:43:54.219453Z","steps":["trace[1764316805] 'agreement among raft nodes before linearized reading' (duration: 283.079355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:54.523443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.220686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:43:54.523712Z","caller":"traceutil/trace.go:171","msg":"trace[1642309433] linearizableReadLoop","detail":"{readStateIndex:25117; appliedIndex:25115; }","duration":"113.163513ms","start":"2026-05-11T01:43:54.410531Z","end":"2026-05-11T01:43:54.523695Z","steps":["trace[1642309433] 'read index received' (duration: 51.992µs)","trace[1642309433] 'applied index is now lower than readState.Index' (duration: 113.110461ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:54.523802Z","caller":"traceutil/trace.go:171","msg":"trace[998926524] transaction","detail":"{read_only:false; response_revision:24328; number_of_response:1; }","duration":"298.868791ms","start":"2026-05-11T01:43:54.224918Z","end":"2026-05-11T01:43:54.523787Z","steps":["trace[998926524] 'process raft request' (duration: 24.222086ms)","trace[998926524] 'compare' (duration: 272.799816ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:43:54.523911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.362537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:43:54.523967Z","caller":"traceutil/trace.go:171","msg":"trace[1293751723] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:24329; }","duration":"113.431268ms","start":"2026-05-11T01:43:54.410524Z","end":"2026-05-11T01:43:54.523956Z","steps":["trace[1293751723] 'agreement among raft nodes before linearized reading' (duration: 113.302126ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:54.523947Z","caller":"traceutil/trace.go:171","msg":"trace[1112808121] transaction","detail":"{read_only:false; response_revision:24329; number_of_response:1; }","duration":"255.619089ms","start":"2026-05-11T01:43:54.268287Z","end":"2026-05-11T01:43:54.523906Z","steps":["trace[1112808121] 'process raft request' (duration: 255.347454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:54.524038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.42449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-060fdca0-974c-41c2-a7a0-2ae6d6ee5dd6\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-11T01:43:54.524121Z","caller":"traceutil/trace.go:171","msg":"trace[1099106210] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-060fdca0-974c-41c2-a7a0-2ae6d6ee5dd6; range_end:; response_count:1; response_revision:24329; }","duration":"108.537164ms","start":"2026-05-11T01:43:54.415564Z","end":"2026-05-11T01:43:54.524102Z","steps":["trace[1099106210] 'agreement among raft nodes before linearized reading' (duration: 108.291188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:54.847258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.344657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:43:54.847417Z","caller":"traceutil/trace.go:171","msg":"trace[1580938950] transaction","detail":"{read_only:false; response_revision:24331; number_of_response:1; }","duration":"285.962156ms","start":"2026-05-11T01:43:54.561441Z","end":"2026-05-11T01:43:54.847403Z","steps":["trace[1580938950] 'process raft request' (duration: 285.912985ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:54.847434Z","caller":"traceutil/trace.go:171","msg":"trace[2117061301] transaction","detail":"{read_only:false; response_revision:24330; number_of_response:1; }","duration":"287.104981ms","start":"2026-05-11T01:43:54.560308Z","end":"2026-05-11T01:43:54.847413Z","steps":["trace[2117061301] 'process raft request' (duration: 89.529439ms)","trace[2117061301] 'compare' (duration: 197.064582ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:43:54.992328Z","caller":"traceutil/trace.go:171","msg":"trace[1791203437] transaction","detail":"{read_only:false; response_revision:24332; number_of_response:1; }","duration":"125.482065ms","start":"2026-05-11T01:43:54.866807Z","end":"2026-05-11T01:43:54.992289Z","steps":["trace[1791203437] 'process raft request' (duration: 123.221897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:55.024879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.322536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:43:55.024933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.881331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-11T01:43:55.02494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.643664ms","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-05-11T01:43:55.024963Z","caller":"traceutil/trace.go:171","msg":"trace[1738737731] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24333; }","duration":"121.433999ms","start":"2026-05-11T01:43:54.903512Z","end":"2026-05-11T01:43:55.024946Z","steps":["trace[1738737731] 'agreement among raft nodes before linearized reading' (duration: 121.258115ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:55.024967Z","caller":"traceutil/trace.go:171","msg":"trace[612341729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24333; }","duration":"123.934852ms","start":"2026-05-11T01:43:54.901024Z","end":"2026-05-11T01:43:55.024959Z","steps":["trace[612341729] 'agreement among raft nodes before linearized reading' (duration: 123.776539ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:55.025019Z","caller":"traceutil/trace.go:171","msg":"trace[1704160771] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24333; }","duration":"122.743896ms","start":"2026-05-11T01:43:54.902248Z","end":"2026-05-11T01:43:55.024992Z","steps":["trace[1704160771] 'agreement among raft nodes before linearized reading' (duration: 122.503891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:55.025477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.160438ms","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-05-11T01:43:55.025538Z","caller":"traceutil/trace.go:171","msg":"trace[389616886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24334; }","duration":"125.23133ms","start":"2026-05-11T01:43:54.900295Z","end":"2026-05-11T01:43:55.025526Z","steps":["trace[389616886] 'agreement among raft nodes before linearized reading' (duration: 124.438512ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:56.663039Z","caller":"traceutil/trace.go:171","msg":"trace[2111001637] transaction","detail":"{read_only:false; response_revision:24344; number_of_response:1; }","duration":"132.440463ms","start":"2026-05-11T01:43:56.530567Z","end":"2026-05-11T01:43:56.663007Z","steps":["trace[2111001637] 'process raft request' (duration: 131.82096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:56.663161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.084879ms","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-05-11T01:43:56.663227Z","caller":"traceutil/trace.go:171","msg":"trace[1954288736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24344; }","duration":"135.218452ms","start":"2026-05-11T01:43:56.527992Z","end":"2026-05-11T01:43:56.66321Z","steps":["trace[1954288736] 'agreement among raft nodes before linearized reading' (duration: 134.976967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:43:56.663272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.844726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-060fdca0-974c-41c2-a7a0-2ae6d6ee5dd6\" ","response":"range_response_count:1 size:3936"} {"level":"info","ts":"2026-05-11T01:43:56.663353Z","caller":"traceutil/trace.go:171","msg":"trace[2094918236] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-060fdca0-974c-41c2-a7a0-2ae6d6ee5dd6; range_end:; response_count:1; response_revision:24344; }","duration":"127.955929ms","start":"2026-05-11T01:43:56.535376Z","end":"2026-05-11T01:43:56.663331Z","steps":["trace[2094918236] 'agreement among raft nodes before linearized reading' (duration: 127.676443ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:00.328954Z","caller":"traceutil/trace.go:171","msg":"trace[816804975] transaction","detail":"{read_only:false; response_revision:24389; number_of_response:1; }","duration":"119.61621ms","start":"2026-05-11T01:44:00.209311Z","end":"2026-05-11T01:44:00.328927Z","steps":["trace[816804975] 'process raft request' (duration: 119.378996ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:00.412312Z","caller":"traceutil/trace.go:171","msg":"trace[1055254176] transaction","detail":"{read_only:false; response_revision:24390; number_of_response:1; }","duration":"174.162392ms","start":"2026-05-11T01:44:00.238118Z","end":"2026-05-11T01:44:00.412281Z","steps":["trace[1055254176] 'process raft request' (duration: 173.794205ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:00.412457Z","caller":"traceutil/trace.go:171","msg":"trace[2134372955] linearizableReadLoop","detail":"{readStateIndex:25181; appliedIndex:25177; }","duration":"171.923275ms","start":"2026-05-11T01:44:00.240514Z","end":"2026-05-11T01:44:00.412438Z","steps":["trace[2134372955] 'read index received' (duration: 88.283363ms)","trace[2134372955] 'applied index is now lower than readState.Index' (duration: 83.638222ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:00.412577Z","caller":"traceutil/trace.go:171","msg":"trace[305399293] transaction","detail":"{read_only:false; response_revision:24392; number_of_response:1; }","duration":"173.287104ms","start":"2026-05-11T01:44:00.239279Z","end":"2026-05-11T01:44:00.412566Z","steps":["trace[305399293] 'process raft request' (duration: 172.923076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:00.412731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.200791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:00.412818Z","caller":"traceutil/trace.go:171","msg":"trace[1102773986] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:24392; }","duration":"172.352585ms","start":"2026-05-11T01:44:00.240442Z","end":"2026-05-11T01:44:00.412795Z","steps":["trace[1102773986] 'agreement among raft nodes before linearized reading' (duration: 172.197412ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:00.413172Z","caller":"traceutil/trace.go:171","msg":"trace[705433012] transaction","detail":"{read_only:false; response_revision:24391; number_of_response:1; }","duration":"174.411509ms","start":"2026-05-11T01:44:00.238688Z","end":"2026-05-11T01:44:00.4131Z","steps":["trace[705433012] 'process raft request' (duration: 173.456538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:00.413565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.165563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-11T01:44:00.413774Z","caller":"traceutil/trace.go:171","msg":"trace[1124216051] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24392; }","duration":"136.365057ms","start":"2026-05-11T01:44:00.277344Z","end":"2026-05-11T01:44:00.413709Z","steps":["trace[1124216051] 'agreement among raft nodes before linearized reading' (duration: 135.246914ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:29.473669Z","caller":"traceutil/trace.go:171","msg":"trace[761941768] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"228.496313ms","start":"2026-05-11T01:44:29.245141Z","end":"2026-05-11T01:44:29.473637Z","steps":["trace[761941768] 'process raft request' (duration: 160.54529ms)","trace[761941768] 'compare' (duration: 67.824001ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:29.533696Z","caller":"traceutil/trace.go:171","msg":"trace[1156758426] linearizableReadLoop","detail":"{readStateIndex:25424; appliedIndex:25422; }","duration":"241.91306ms","start":"2026-05-11T01:44:29.291746Z","end":"2026-05-11T01:44:29.533659Z","steps":["trace[1156758426] 'read index received' (duration: 114.016593ms)","trace[1156758426] 'applied index is now lower than readState.Index' (duration: 127.894717ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:29.533978Z","caller":"traceutil/trace.go:171","msg":"trace[1927555917] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"257.188294ms","start":"2026-05-11T01:44:29.276751Z","end":"2026-05-11T01:44:29.533939Z","steps":["trace[1927555917] 'process raft request' (duration: 256.594022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:29.534321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.519383ms","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-05-11T01:44:29.534403Z","caller":"traceutil/trace.go:171","msg":"trace[1859779655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24628; }","duration":"242.693477ms","start":"2026-05-11T01:44:29.291696Z","end":"2026-05-11T01:44:29.53439Z","steps":["trace[1859779655] 'agreement among raft nodes before linearized reading' (duration: 242.232267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:29.534493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.034618ms","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-05-11T01:44:29.534544Z","caller":"traceutil/trace.go:171","msg":"trace[1363267803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24628; }","duration":"231.1309ms","start":"2026-05-11T01:44:29.3034Z","end":"2026-05-11T01:44:29.534531Z","steps":["trace[1363267803] 'agreement among raft nodes before linearized reading' (duration: 230.853734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:29.534816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.443668ms","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-05-11T01:44:29.534947Z","caller":"traceutil/trace.go:171","msg":"trace[145488759] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24628; }","duration":"224.609351ms","start":"2026-05-11T01:44:29.310289Z","end":"2026-05-11T01:44:29.534899Z","steps":["trace[145488759] 'agreement among raft nodes before linearized reading' (duration: 224.292285ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:29.732014Z","caller":"traceutil/trace.go:171","msg":"trace[721321500] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"192.190594ms","start":"2026-05-11T01:44:29.539789Z","end":"2026-05-11T01:44:29.73198Z","steps":["trace[721321500] 'process raft request' (duration: 126.186522ms)","trace[721321500] 'compare' (duration: 65.386029ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:29.736387Z","caller":"traceutil/trace.go:171","msg":"trace[1591297346] linearizableReadLoop","detail":"{readStateIndex:25427; appliedIndex:25424; }","duration":"103.839416ms","start":"2026-05-11T01:44:29.632537Z","end":"2026-05-11T01:44:29.736376Z","steps":["trace[1591297346] 'read index received' (duration: 33.450631ms)","trace[1591297346] 'applied index is now lower than readState.Index' (duration: 70.387665ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:29.73649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.933128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:29.736514Z","caller":"traceutil/trace.go:171","msg":"trace[976644474] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24631; }","duration":"104.01948ms","start":"2026-05-11T01:44:29.632487Z","end":"2026-05-11T01:44:29.736507Z","steps":["trace[976644474] 'agreement among raft nodes before linearized reading' (duration: 103.930048ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:29.736717Z","caller":"traceutil/trace.go:171","msg":"trace[205202205] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"192.652322ms","start":"2026-05-11T01:44:29.544057Z","end":"2026-05-11T01:44:29.736709Z","steps":["trace[205202205] 'process raft request' (duration: 192.226253ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:29.736807Z","caller":"traceutil/trace.go:171","msg":"trace[87304730] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"192.589021ms","start":"2026-05-11T01:44:29.544211Z","end":"2026-05-11T01:44:29.7368Z","steps":["trace[87304730] 'process raft request' (duration: 192.136592ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:31.310518Z","caller":"traceutil/trace.go:171","msg":"trace[1145636069] transaction","detail":"{read_only:false; response_revision:24641; number_of_response:1; }","duration":"127.855417ms","start":"2026-05-11T01:44:31.182633Z","end":"2026-05-11T01:44:31.310489Z","steps":["trace[1145636069] 'process raft request' (duration: 127.608692ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:31.594097Z","caller":"traceutil/trace.go:171","msg":"trace[838906392] transaction","detail":"{read_only:false; response_revision:24642; number_of_response:1; }","duration":"111.661383ms","start":"2026-05-11T01:44:31.482394Z","end":"2026-05-11T01:44:31.594055Z","steps":["trace[838906392] 'process raft request' (duration: 103.683253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:31.763602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.566011ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-11T01:44:32.027507Z","caller":"traceutil/trace.go:171","msg":"trace[1743955229] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"259.026953ms","start":"2026-05-11T01:44:31.768453Z","end":"2026-05-11T01:44:32.02748Z","steps":["trace[1743955229] 'process raft request' (duration: 258.803608ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:32.165132Z","caller":"traceutil/trace.go:171","msg":"trace[2088477263] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"395.703526ms","start":"2026-05-11T01:44:31.769398Z","end":"2026-05-11T01:44:32.165102Z","steps":["trace[2088477263] 'process raft request' (duration: 315.101534ms)","trace[2088477263] 'compare' (duration: 79.924128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:32.165284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:31.76937Z","time spent":"395.83293ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:32.165624Z","caller":"traceutil/trace.go:171","msg":"trace[1915628080] linearizableReadLoop","detail":"{readStateIndex:25442; appliedIndex:25439; }","duration":"392.769895ms","start":"2026-05-11T01:44:31.772836Z","end":"2026-05-11T01:44:32.165605Z","steps":["trace[1915628080] 'read index received' (duration: 254.413886ms)","trace[1915628080] 'applied index is now lower than readState.Index' (duration: 138.354849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:32.165767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.927008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:32.165823Z","caller":"traceutil/trace.go:171","msg":"trace[634025215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24645; }","duration":"393.004249ms","start":"2026-05-11T01:44:31.772807Z","end":"2026-05-11T01:44:32.165811Z","steps":["trace[634025215] 'agreement among raft nodes before linearized reading' (duration: 392.859116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:32.165898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:31.772798Z","time spent":"393.089521ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T01:44:32.166139Z","caller":"traceutil/trace.go:171","msg":"trace[847874315] transaction","detail":"{read_only:false; response_revision:24645; number_of_response:1; }","duration":"396.643567ms","start":"2026-05-11T01:44:31.769412Z","end":"2026-05-11T01:44:32.166056Z","steps":["trace[847874315] 'process raft request' (duration: 395.38057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:32.16629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:31.76939Z","time spent":"396.815681ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:33.881049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.75686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:33.881104Z","caller":"traceutil/trace.go:171","msg":"trace[708391227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24655; }","duration":"107.856442ms","start":"2026-05-11T01:44:33.773234Z","end":"2026-05-11T01:44:33.881091Z","steps":["trace[708391227] 'range keys from in-memory index tree' (duration: 107.674549ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:34.192676Z","caller":"traceutil/trace.go:171","msg":"trace[477159469] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"156.861882ms","start":"2026-05-11T01:44:34.035786Z","end":"2026-05-11T01:44:34.192648Z","steps":["trace[477159469] 'process raft request' (duration: 156.450784ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:34.318981Z","caller":"traceutil/trace.go:171","msg":"trace[2143526710] linearizableReadLoop","detail":"{readStateIndex:25454; appliedIndex:25453; }","duration":"122.305639ms","start":"2026-05-11T01:44:34.19665Z","end":"2026-05-11T01:44:34.318956Z","steps":["trace[2143526710] 'read index received' (duration: 121.998102ms)","trace[2143526710] 'applied index is now lower than readState.Index' (duration: 306.237µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:34.319041Z","caller":"traceutil/trace.go:171","msg":"trace[1476165499] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"122.662636ms","start":"2026-05-11T01:44:34.196343Z","end":"2026-05-11T01:44:34.319006Z","steps":["trace[1476165499] 'process raft request' (duration: 122.348629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:34.31921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.542404ms","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-05-11T01:44:34.319258Z","caller":"traceutil/trace.go:171","msg":"trace[1447795036] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24657; }","duration":"122.643416ms","start":"2026-05-11T01:44:34.196599Z","end":"2026-05-11T01:44:34.319242Z","steps":["trace[1447795036] 'agreement among raft nodes before linearized reading' (duration: 122.454032ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:34.319579Z","caller":"traceutil/trace.go:171","msg":"trace[1862151909] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"122.474311ms","start":"2026-05-11T01:44:34.197073Z","end":"2026-05-11T01:44:34.319548Z","steps":["trace[1862151909] 'process raft request' (duration: 122.290037ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:34.615217Z","caller":"traceutil/trace.go:171","msg":"trace[130311899] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"185.521311ms","start":"2026-05-11T01:44:34.429673Z","end":"2026-05-11T01:44:34.615195Z","steps":["trace[130311899] 'process raft request' (duration: 185.359498ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:34.61595Z","caller":"traceutil/trace.go:171","msg":"trace[1176921828] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"131.672627ms","start":"2026-05-11T01:44:34.48425Z","end":"2026-05-11T01:44:34.615922Z","steps":["trace[1176921828] 'process raft request' (duration: 131.243338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:34.925719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.335274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:44:34.92599Z","caller":"traceutil/trace.go:171","msg":"trace[1967898787] linearizableReadLoop","detail":"{readStateIndex:25462; appliedIndex:25460; }","duration":"289.587803ms","start":"2026-05-11T01:44:34.63638Z","end":"2026-05-11T01:44:34.925967Z","steps":["trace[1967898787] 'read index received' (duration: 146.920242ms)","trace[1967898787] 'applied index is now lower than readState.Index' (duration: 142.666091ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:34.926093Z","caller":"traceutil/trace.go:171","msg":"trace[1211129273] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"304.430428ms","start":"2026-05-11T01:44:34.621646Z","end":"2026-05-11T01:44:34.926076Z","steps":["trace[1211129273] 'process raft request' (duration: 161.633464ms)","trace[1211129273] 'compare' (duration: 142.219582ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:34.926184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:34.62162Z","time spent":"304.516631ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:44:34.926309Z","caller":"traceutil/trace.go:171","msg":"trace[1706289629] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"303.424967ms","start":"2026-05-11T01:44:34.622827Z","end":"2026-05-11T01:44:34.926252Z","steps":["trace[1706289629] 'process raft request' (duration: 303.051559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:34.926352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.94695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:44:34.926409Z","caller":"traceutil/trace.go:171","msg":"trace[1912843191] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24665; }","duration":"290.062983ms","start":"2026-05-11T01:44:34.636331Z","end":"2026-05-11T01:44:34.926394Z","steps":["trace[1912843191] 'agreement among raft nodes before linearized reading' (duration: 289.92396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:34.926466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:34.622795Z","time spent":"303.586851ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:34.951695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.204154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:34.951773Z","caller":"traceutil/trace.go:171","msg":"trace[998999628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24666; }","duration":"176.338697ms","start":"2026-05-11T01:44:34.77542Z","end":"2026-05-11T01:44:34.951759Z","steps":["trace[998999628] 'agreement among raft nodes before linearized reading' (duration: 176.137642ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:35.11283Z","caller":"traceutil/trace.go:171","msg":"trace[778672984] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"147.3169ms","start":"2026-05-11T01:44:34.965488Z","end":"2026-05-11T01:44:35.112805Z","steps":["trace[778672984] 'process raft request' (duration: 66.588365ms)","trace[778672984] 'compare' (duration: 80.411558ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:35.441603Z","caller":"traceutil/trace.go:171","msg":"trace[1718322113] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"112.03703ms","start":"2026-05-11T01:44:35.32955Z","end":"2026-05-11T01:44:35.441587Z","steps":["trace[1718322113] 'process raft request' (duration: 111.911817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:35.900457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.398872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:35.900516Z","caller":"traceutil/trace.go:171","msg":"trace[910093550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24671; }","duration":"123.519495ms","start":"2026-05-11T01:44:35.776983Z","end":"2026-05-11T01:44:35.900503Z","steps":["trace[910093550] 'range keys from in-memory index tree' (duration: 123.290849ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:36.471301Z","caller":"traceutil/trace.go:171","msg":"trace[1442868701] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"109.20411ms","start":"2026-05-11T01:44:36.36207Z","end":"2026-05-11T01:44:36.471274Z","steps":["trace[1442868701] 'process raft request' (duration: 109.013766ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:36.494184Z","caller":"traceutil/trace.go:171","msg":"trace[1236925692] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"131.813351ms","start":"2026-05-11T01:44:36.362332Z","end":"2026-05-11T01:44:36.494146Z","steps":["trace[1236925692] 'process raft request' (duration: 131.497154ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:36.689207Z","caller":"traceutil/trace.go:171","msg":"trace[70766710] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"140.870253ms","start":"2026-05-11T01:44:36.548315Z","end":"2026-05-11T01:44:36.689185Z","steps":["trace[70766710] 'process raft request' (duration: 68.358712ms)","trace[70766710] 'compare' (duration: 72.375729ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:36.856588Z","caller":"traceutil/trace.go:171","msg":"trace[1074271093] linearizableReadLoop","detail":"{readStateIndex:25475; appliedIndex:25473; }","duration":"165.002546ms","start":"2026-05-11T01:44:36.691558Z","end":"2026-05-11T01:44:36.856561Z","steps":["trace[1074271093] 'read index received' (duration: 26.36791ms)","trace[1074271093] 'applied index is now lower than readState.Index' (duration: 138.633116ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:44:36.856863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.079608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:36.856933Z","caller":"traceutil/trace.go:171","msg":"trace[1626210323] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24677; }","duration":"126.193631ms","start":"2026-05-11T01:44:36.730721Z","end":"2026-05-11T01:44:36.856915Z","steps":["trace[1626210323] 'agreement among raft nodes before linearized reading' (duration: 126.075539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:36.857053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.481625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-11T01:44:36.857131Z","caller":"traceutil/trace.go:171","msg":"trace[1287190346] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24677; }","duration":"165.622788ms","start":"2026-05-11T01:44:36.691491Z","end":"2026-05-11T01:44:36.857114Z","steps":["trace[1287190346] 'agreement among raft nodes before linearized reading' (duration: 165.370213ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:36.857224Z","caller":"traceutil/trace.go:171","msg":"trace[1030399854] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"234.886301ms","start":"2026-05-11T01:44:36.621827Z","end":"2026-05-11T01:44:36.856714Z","steps":["trace[1030399854] 'process raft request' (duration: 230.829064ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:37.076787Z","caller":"traceutil/trace.go:171","msg":"trace[857798980] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"119.254813ms","start":"2026-05-11T01:44:36.957493Z","end":"2026-05-11T01:44:37.076748Z","steps":["trace[857798980] 'process raft request' (duration: 119.125031ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:37.076916Z","caller":"traceutil/trace.go:171","msg":"trace[1049300091] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"143.726675ms","start":"2026-05-11T01:44:36.933143Z","end":"2026-05-11T01:44:37.076869Z","steps":["trace[1049300091] 'process raft request' (duration: 58.79346ms)","trace[1049300091] 'compare' (duration: 84.454854ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:37.26718Z","caller":"traceutil/trace.go:171","msg":"trace[1945333919] transaction","detail":"{read_only:false; response_revision:24681; number_of_response:1; }","duration":"150.011376ms","start":"2026-05-11T01:44:37.11712Z","end":"2026-05-11T01:44:37.267131Z","steps":["trace[1945333919] 'process raft request' (duration: 133.504296ms)","trace[1945333919] 'compare' (duration: 16.166093ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:37.267397Z","caller":"traceutil/trace.go:171","msg":"trace[1459784596] transaction","detail":"{read_only:false; response_revision:24683; number_of_response:1; }","duration":"126.57061ms","start":"2026-05-11T01:44:37.140808Z","end":"2026-05-11T01:44:37.267379Z","steps":["trace[1459784596] 'process raft request' (duration: 126.520228ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:37.267488Z","caller":"traceutil/trace.go:171","msg":"trace[1317097711] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"146.719378ms","start":"2026-05-11T01:44:37.120755Z","end":"2026-05-11T01:44:37.267474Z","steps":["trace[1317097711] 'process raft request' (duration: 146.493433ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:37.851257Z","caller":"traceutil/trace.go:171","msg":"trace[1308216079] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"113.244027ms","start":"2026-05-11T01:44:37.737966Z","end":"2026-05-11T01:44:37.85121Z","steps":["trace[1308216079] 'process raft request' (duration: 112.865089ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:39.06605Z","caller":"traceutil/trace.go:171","msg":"trace[338376525] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"122.166206ms","start":"2026-05-11T01:44:38.943858Z","end":"2026-05-11T01:44:39.066024Z","steps":["trace[338376525] 'process raft request' (duration: 121.968991ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:39.254776Z","caller":"traceutil/trace.go:171","msg":"trace[111406195] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"172.066095ms","start":"2026-05-11T01:44:39.082672Z","end":"2026-05-11T01:44:39.254738Z","steps":["trace[111406195] 'process raft request' (duration: 110.082138ms)","trace[111406195] 'compare' (duration: 61.814193ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:39.522863Z","caller":"traceutil/trace.go:171","msg":"trace[934744156] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"250.024193ms","start":"2026-05-11T01:44:39.272809Z","end":"2026-05-11T01:44:39.522833Z","steps":["trace[934744156] 'process raft request' (duration: 156.984996ms)","trace[934744156] 'compare' (duration: 92.881694ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:39.664797Z","caller":"traceutil/trace.go:171","msg":"trace[770764455] linearizableReadLoop","detail":"{readStateIndex:25495; appliedIndex:25492; }","duration":"253.293361ms","start":"2026-05-11T01:44:39.411478Z","end":"2026-05-11T01:44:39.664771Z","steps":["trace[770764455] 'read index received' (duration: 18.274588ms)","trace[770764455] 'applied index is now lower than readState.Index' (duration: 235.016793ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:44:39.664855Z","caller":"traceutil/trace.go:171","msg":"trace[394336785] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"389.179038ms","start":"2026-05-11T01:44:39.275645Z","end":"2026-05-11T01:44:39.664824Z","steps":["trace[394336785] 'process raft request' (duration: 389.018645ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:44:39.664853Z","caller":"traceutil/trace.go:171","msg":"trace[744655787] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"391.558099ms","start":"2026-05-11T01:44:39.273242Z","end":"2026-05-11T01:44:39.664801Z","steps":["trace[744655787] 'process raft request' (duration: 391.255332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:39.665014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:39.275636Z","time spent":"389.287421ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:39.665047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.558646ms","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-05-11T01:44:39.665086Z","caller":"traceutil/trace.go:171","msg":"trace[1183634901] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24697; }","duration":"253.653418ms","start":"2026-05-11T01:44:39.411422Z","end":"2026-05-11T01:44:39.665075Z","steps":["trace[1183634901] 'agreement among raft nodes before linearized reading' (duration: 253.483825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:39.665075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:44:39.273221Z","time spent":"391.733472ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:44:39.675451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.18808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:44:39.675577Z","caller":"traceutil/trace.go:171","msg":"trace[406765876] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24698; }","duration":"246.383425ms","start":"2026-05-11T01:44:39.429168Z","end":"2026-05-11T01:44:39.675551Z","steps":["trace[406765876] 'agreement among raft nodes before linearized reading' (duration: 246.16374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:44:40.050506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.270887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418459"} {"level":"info","ts":"2026-05-11T01:44:40.050618Z","caller":"traceutil/trace.go:171","msg":"trace[154794518] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24700; }","duration":"153.446231ms","start":"2026-05-11T01:44:39.89714Z","end":"2026-05-11T01:44:40.050586Z","steps":["trace[154794518] 'range keys from in-memory index tree' (duration: 152.99234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.935626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.987649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:03.935709Z","caller":"traceutil/trace.go:171","msg":"trace[968641001] linearizableReadLoop","detail":"{readStateIndex:25809; appliedIndex:25808; }","duration":"289.894169ms","start":"2026-05-11T01:45:03.645802Z","end":"2026-05-11T01:45:03.935696Z","steps":["trace[968641001] 'read index received' (duration: 121.815578ms)","trace[968641001] 'applied index is now lower than readState.Index' (duration: 168.077851ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:03.935879Z","caller":"traceutil/trace.go:171","msg":"trace[586255255] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"298.768567ms","start":"2026-05-11T01:45:03.637104Z","end":"2026-05-11T01:45:03.935872Z","steps":["trace[586255255] 'process raft request' (duration: 130.479482ms)","trace[586255255] 'compare' (duration: 167.862516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:03.936052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.318828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/openstack/manila-share-7fcb88b9df\" ","response":"range_response_count:1 size:9074"} {"level":"info","ts":"2026-05-11T01:45:03.936075Z","caller":"traceutil/trace.go:171","msg":"trace[2016898778] range","detail":"{range_begin:/registry/replicasets/openstack/manila-share-7fcb88b9df; range_end:; response_count:1; response_revision:25005; }","duration":"306.343878ms","start":"2026-05-11T01:45:03.629724Z","end":"2026-05-11T01:45:03.936068Z","steps":["trace[2016898778] 'agreement among raft nodes before linearized reading' (duration: 306.258797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.936095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:03.629704Z","time spent":"306.384799ms","remote":"127.0.0.1:42010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9098,"request content":"key:\"/registry/replicasets/openstack/manila-share-7fcb88b9df\" "} {"level":"warn","ts":"2026-05-11T01:45:03.936231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.702436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/openstack/manila-scheduler-58f7ddd56d\" ","response":"range_response_count:1 size:6439"} {"level":"info","ts":"2026-05-11T01:45:03.936316Z","caller":"traceutil/trace.go:171","msg":"trace[1072734704] range","detail":"{range_begin:/registry/replicasets/openstack/manila-scheduler-58f7ddd56d; range_end:; response_count:1; response_revision:25005; }","duration":"306.810038ms","start":"2026-05-11T01:45:03.629486Z","end":"2026-05-11T01:45:03.936296Z","steps":["trace[1072734704] 'agreement among raft nodes before linearized reading' (duration: 306.581283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.936357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:03.629477Z","time spent":"306.870089ms","remote":"127.0.0.1:42010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6463,"request content":"key:\"/registry/replicasets/openstack/manila-scheduler-58f7ddd56d\" "} {"level":"warn","ts":"2026-05-11T01:45:03.962646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.293309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:45:03.962703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.105358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/manila-scheduler\" ","response":"range_response_count:1 size:537"} {"level":"info","ts":"2026-05-11T01:45:03.962717Z","caller":"traceutil/trace.go:171","msg":"trace[1153369528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25006; }","duration":"187.386511ms","start":"2026-05-11T01:45:03.775315Z","end":"2026-05-11T01:45:03.962702Z","steps":["trace[1153369528] 'agreement among raft nodes before linearized reading' (duration: 187.249838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.962715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.330068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-05-11T01:45:03.962754Z","caller":"traceutil/trace.go:171","msg":"trace[18140187] range","detail":"{range_begin:/registry/serviceaccounts/openstack/manila-scheduler; range_end:; response_count:1; response_revision:25006; }","duration":"157.19946ms","start":"2026-05-11T01:45:03.80554Z","end":"2026-05-11T01:45:03.962739Z","steps":["trace[18140187] 'agreement among raft nodes before linearized reading' (duration: 156.993725ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:03.962792Z","caller":"traceutil/trace.go:171","msg":"trace[890046675] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:25006; }","duration":"313.45278ms","start":"2026-05-11T01:45:03.649321Z","end":"2026-05-11T01:45:03.962774Z","steps":["trace[890046675] 'agreement among raft nodes before linearized reading' (duration: 313.259276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.962802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.837425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-11T01:45:03.962799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.098609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-11T01:45:03.962831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:03.649302Z","time spent":"313.519591ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":675,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"info","ts":"2026-05-11T01:45:03.962843Z","caller":"traceutil/trace.go:171","msg":"trace[409531296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25006; }","duration":"134.164981ms","start":"2026-05-11T01:45:03.828669Z","end":"2026-05-11T01:45:03.962834Z","steps":["trace[409531296] 'agreement among raft nodes before linearized reading' (duration: 134.065279ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:03.962835Z","caller":"traceutil/trace.go:171","msg":"trace[1038217545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25006; }","duration":"142.903097ms","start":"2026-05-11T01:45:03.819923Z","end":"2026-05-11T01:45:03.962826Z","steps":["trace[1038217545] 'agreement among raft nodes before linearized reading' (duration: 142.845705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.962837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.731181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/manila-data\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-05-11T01:45:03.962944Z","caller":"traceutil/trace.go:171","msg":"trace[1254132397] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"149.784582ms","start":"2026-05-11T01:45:03.813126Z","end":"2026-05-11T01:45:03.96291Z","steps":["trace[1254132397] 'process raft request' (duration: 149.248921ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:03.962948Z","caller":"traceutil/trace.go:171","msg":"trace[123494171] range","detail":"{range_begin:/registry/serviceaccounts/openstack/manila-data; range_end:; response_count:1; response_revision:25006; }","duration":"157.854564ms","start":"2026-05-11T01:45:03.805067Z","end":"2026-05-11T01:45:03.962922Z","steps":["trace[123494171] 'agreement among raft nodes before linearized reading' (duration: 157.592318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.962739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.466845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/manila-api\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-11T01:45:03.963019Z","caller":"traceutil/trace.go:171","msg":"trace[408092454] range","detail":"{range_begin:/registry/serviceaccounts/openstack/manila-api; range_end:; response_count:1; response_revision:25006; }","duration":"157.768141ms","start":"2026-05-11T01:45:03.80524Z","end":"2026-05-11T01:45:03.963008Z","steps":["trace[408092454] 'agreement among raft nodes before linearized reading' (duration: 157.396264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.963054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.158138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-11T01:45:03.963103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.383594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/manila-share\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-11T01:45:03.963179Z","caller":"traceutil/trace.go:171","msg":"trace[453769048] range","detail":"{range_begin:/registry/serviceaccounts/openstack/manila-share; range_end:; response_count:1; response_revision:25006; }","duration":"157.470415ms","start":"2026-05-11T01:45:03.805688Z","end":"2026-05-11T01:45:03.963158Z","steps":["trace[453769048] 'agreement among raft nodes before linearized reading' (duration: 157.293982ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:03.963165Z","caller":"traceutil/trace.go:171","msg":"trace[1448028543] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25006; }","duration":"188.357541ms","start":"2026-05-11T01:45:03.774786Z","end":"2026-05-11T01:45:03.963144Z","steps":["trace[1448028543] 'agreement among raft nodes before linearized reading' (duration: 188.099056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.963298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.607862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"info","ts":"2026-05-11T01:45:03.96337Z","caller":"traceutil/trace.go:171","msg":"trace[1827247466] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:25006; }","duration":"304.697743ms","start":"2026-05-11T01:45:03.658651Z","end":"2026-05-11T01:45:03.963349Z","steps":["trace[1827247466] 'agreement among raft nodes before linearized reading' (duration: 303.935748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:03.963419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:03.658639Z","time spent":"304.765626ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1846,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-11T01:45:04.21004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.981962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:04.210162Z","caller":"traceutil/trace.go:171","msg":"trace[1978913322] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"243.088125ms","start":"2026-05-11T01:45:03.967054Z","end":"2026-05-11T01:45:04.210142Z","steps":["trace[1978913322] 'process raft request' (duration: 103.918558ms)","trace[1978913322] 'compare' (duration: 138.822619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:04.465973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.143486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:04.466168Z","caller":"traceutil/trace.go:171","msg":"trace[1878266950] linearizableReadLoop","detail":"{readStateIndex:25812; appliedIndex:25810; }","duration":"497.102633ms","start":"2026-05-11T01:45:03.969049Z","end":"2026-05-11T01:45:04.466152Z","steps":["trace[1878266950] 'read index received' (duration: 102.09509ms)","trace[1878266950] 'applied index is now lower than readState.Index' (duration: 395.006383ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:04.466263Z","caller":"traceutil/trace.go:171","msg":"trace[799224111] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"496.119591ms","start":"2026-05-11T01:45:03.970134Z","end":"2026-05-11T01:45:04.466254Z","steps":["trace[799224111] 'process raft request' (duration: 495.944487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.466324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:03.970123Z","time spent":"496.168292ms","remote":"127.0.0.1:41714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7760,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:04.466312Z","caller":"traceutil/trace.go:171","msg":"trace[23899355] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"498.9152ms","start":"2026-05-11T01:45:03.967359Z","end":"2026-05-11T01:45:04.466274Z","steps":["trace[23899355] 'process raft request' (duration: 352.345565ms)","trace[23899355] 'compare' (duration: 146.023523ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:04.46663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.463721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"warn","ts":"2026-05-11T01:45:04.466593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.493636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:45:04.466651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.807594ms","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-05-11T01:45:04.466702Z","caller":"traceutil/trace.go:171","msg":"trace[1083565343] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:25009; }","duration":"474.584674ms","start":"2026-05-11T01:45:03.992099Z","end":"2026-05-11T01:45:04.466683Z","steps":["trace[1083565343] 'agreement among raft nodes before linearized reading' (duration: 474.41893ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:04.466719Z","caller":"traceutil/trace.go:171","msg":"trace[1697586400] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25009; }","duration":"422.67218ms","start":"2026-05-11T01:45:04.044023Z","end":"2026-05-11T01:45:04.466695Z","steps":["trace[1697586400] 'agreement among raft nodes before linearized reading' (duration: 422.478726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.46672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.640774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-05-11T01:45:04.466743Z","caller":"traceutil/trace.go:171","msg":"trace[1900513137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25009; }","duration":"479.952999ms","start":"2026-05-11T01:45:03.986767Z","end":"2026-05-11T01:45:04.46672Z","steps":["trace[1900513137] 'agreement among raft nodes before linearized reading' (duration: 479.717054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.466751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:03.992079Z","time spent":"474.660645ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3590,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-05-11T01:45:04.466775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.044002Z","time spent":"422.761223ms","remote":"127.0.0.1:50528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:45:04.466796Z","caller":"traceutil/trace.go:171","msg":"trace[484119241] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:25009; }","duration":"497.742736ms","start":"2026-05-11T01:45:03.969031Z","end":"2026-05-11T01:45:04.466774Z","steps":["trace[484119241] 'agreement among raft nodes before linearized reading' (duration: 497.4643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.466807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:03.986749Z","time spent":"480.0423ms","remote":"127.0.0.1:41812","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":"warn","ts":"2026-05-11T01:45:04.466855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:03.969023Z","time spent":"497.821377ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":733,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"warn","ts":"2026-05-11T01:45:04.467463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:03.967341Z","time spent":"499.310508ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:04.721782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.681962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:04.721946Z","caller":"traceutil/trace.go:171","msg":"trace[2028604726] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"249.080443ms","start":"2026-05-11T01:45:04.472846Z","end":"2026-05-11T01:45:04.721926Z","steps":["trace[2028604726] 'process raft request' (duration: 106.175606ms)","trace[2028604726] 'compare' (duration: 142.488558ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:04.789411Z","caller":"traceutil/trace.go:171","msg":"trace[2085360004] linearizableReadLoop","detail":"{readStateIndex:25815; appliedIndex:25813; }","duration":"315.876502ms","start":"2026-05-11T01:45:04.473511Z","end":"2026-05-11T01:45:04.789388Z","steps":["trace[2085360004] 'read index received' (duration: 105.526393ms)","trace[2085360004] 'applied index is now lower than readState.Index' (duration: 210.349209ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:04.78952Z","caller":"traceutil/trace.go:171","msg":"trace[1243681209] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"310.048139ms","start":"2026-05-11T01:45:04.479462Z","end":"2026-05-11T01:45:04.78951Z","steps":["trace[1243681209] 'process raft request' (duration: 309.864175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.789617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.479445Z","time spent":"310.100329ms","remote":"127.0.0.1:41714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7027,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:04.789661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.138747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-05-11T01:45:04.789709Z","caller":"traceutil/trace.go:171","msg":"trace[1480576154] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:25012; }","duration":"316.187618ms","start":"2026-05-11T01:45:04.473508Z","end":"2026-05-11T01:45:04.789695Z","steps":["trace[1480576154] 'agreement among raft nodes before linearized reading' (duration: 316.009815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.78975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.473485Z","time spent":"316.256969ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3590,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-05-11T01:45:04.789648Z","caller":"traceutil/trace.go:171","msg":"trace[478006387] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"316.493035ms","start":"2026-05-11T01:45:04.473115Z","end":"2026-05-11T01:45:04.789608Z","steps":["trace[478006387] 'process raft request' (duration: 316.067526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.78998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.473093Z","time spent":"316.799221ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:04.989645Z","caller":"traceutil/trace.go:171","msg":"trace[653660527] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"345.143262ms","start":"2026-05-11T01:45:04.644468Z","end":"2026-05-11T01:45:04.989611Z","steps":["trace[653660527] 'process raft request' (duration: 344.801665ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:04.989769Z","caller":"traceutil/trace.go:171","msg":"trace[664282156] linearizableReadLoop","detail":"{readStateIndex:25818; appliedIndex:25816; }","duration":"200.268375ms","start":"2026-05-11T01:45:04.789475Z","end":"2026-05-11T01:45:04.989744Z","steps":["trace[664282156] 'read index received' (duration: 199.878686ms)","trace[664282156] 'applied index is now lower than readState.Index' (duration: 387.909µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:04.989862Z","caller":"traceutil/trace.go:171","msg":"trace[1792656977] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"271.318914ms","start":"2026-05-11T01:45:04.718495Z","end":"2026-05-11T01:45:04.989814Z","steps":["trace[1792656977] 'process raft request' (duration: 271.043868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.989879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.644442Z","time spent":"345.318817ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:04.990058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.750503ms","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-05-11T01:45:04.990097Z","caller":"traceutil/trace.go:171","msg":"trace[1862196708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25014; }","duration":"327.834305ms","start":"2026-05-11T01:45:04.662253Z","end":"2026-05-11T01:45:04.990088Z","steps":["trace[1862196708] 'agreement among raft nodes before linearized reading' (duration: 327.618741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.990122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.662229Z","time spent":"327.886696ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T01:45:04.990122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.340778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"warn","ts":"2026-05-11T01:45:04.990142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.349446ms","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-05-11T01:45:04.990208Z","caller":"traceutil/trace.go:171","msg":"trace[40652107] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:25014; }","duration":"514.4453ms","start":"2026-05-11T01:45:04.475743Z","end":"2026-05-11T01:45:04.990188Z","steps":["trace[40652107] 'agreement among raft nodes before linearized reading' (duration: 514.183775ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:04.990273Z","caller":"traceutil/trace.go:171","msg":"trace[326276238] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25014; }","duration":"280.516749ms","start":"2026-05-11T01:45:04.709715Z","end":"2026-05-11T01:45:04.990232Z","steps":["trace[326276238] 'agreement among raft nodes before linearized reading' (duration: 280.128531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.990252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.475737Z","time spent":"514.504461ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1310,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-05-11T01:45:04.991682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.469095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-05-11T01:45:04.991748Z","caller":"traceutil/trace.go:171","msg":"trace[1484497608] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:25015; }","duration":"197.534517ms","start":"2026-05-11T01:45:04.794196Z","end":"2026-05-11T01:45:04.991731Z","steps":["trace[1484497608] 'agreement among raft nodes before linearized reading' (duration: 197.369643ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:04.992034Z","caller":"traceutil/trace.go:171","msg":"trace[510173777] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"111.160851ms","start":"2026-05-11T01:45:04.880856Z","end":"2026-05-11T01:45:04.992016Z","steps":["trace[510173777] 'process raft request' (duration: 110.571318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.992464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.251464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:45:04.992513Z","caller":"traceutil/trace.go:171","msg":"trace[2109821187] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25015; }","duration":"151.303056ms","start":"2026-05-11T01:45:04.841197Z","end":"2026-05-11T01:45:04.9925Z","steps":["trace[2109821187] 'agreement among raft nodes before linearized reading' (duration: 151.226353ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:43.47583Z","caller":"traceutil/trace.go:171","msg":"trace[1368532526] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"110.493258ms","start":"2026-05-11T01:45:43.36529Z","end":"2026-05-11T01:45:43.475783Z","steps":["trace[1368532526] 'process raft request' (duration: 36.454605ms)","trace[1368532526] 'compare' (duration: 73.836889ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:47:23.47365Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23418} {"level":"info","ts":"2026-05-11T01:47:23.613531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23418,"took":"136.019591ms","hash":1051763240,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":36929536,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-11T01:47:23.613586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1051763240,"revision":23418,"compact-revision":20249} {"level":"info","ts":"2026-05-11T01:47:25.741502Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a5b.wal"} {"level":"info","ts":"2026-05-11T01:52:23.479409Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26342} {"level":"info","ts":"2026-05-11T01:52:23.625035Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26342,"took":"141.797933ms","hash":1774261297,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31969280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T01:52:23.625113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1774261297,"revision":26342,"compact-revision":23418} {"level":"info","ts":"2026-05-11T01:53:03.124901Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:53:03.130151Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-11T01:53:03.130257Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-11T01:57:23.487046Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28801} {"level":"info","ts":"2026-05-11T01:57:23.62993Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28801,"took":"139.962402ms","hash":3412252835,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":29929472,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T01:57:23.630003Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3412252835,"revision":28801,"compact-revision":26342} {"level":"info","ts":"2026-05-11T02:01:52.793127Z","caller":"traceutil/trace.go:171","msg":"trace[1539362544] transaction","detail":"{read_only:false; response_revision:33178; number_of_response:1; }","duration":"130.35439ms","start":"2026-05-11T02:01:52.662741Z","end":"2026-05-11T02:01:52.793096Z","steps":["trace[1539362544] 'process raft request' (duration: 130.106345ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T02:01:55.252176Z","caller":"traceutil/trace.go:171","msg":"trace[1538080590] transaction","detail":"{read_only:false; response_revision:33199; number_of_response:1; }","duration":"101.731641ms","start":"2026-05-11T02:01:55.150399Z","end":"2026-05-11T02:01:55.252131Z","steps":["trace[1538080590] 'process raft request' (duration: 77.402275ms)","trace[1538080590] 'compare' (duration: 24.095391ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T02:02:23.49641Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31017} {"level":"info","ts":"2026-05-11T02:02:23.618783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31017,"took":"116.795172ms","hash":2118917636,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":32092160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T02:02:23.618861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2118917636,"revision":31017,"compact-revision":28801} {"level":"info","ts":"2026-05-11T02:07:23.513679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33413} {"level":"info","ts":"2026-05-11T02:07:23.674321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33413,"took":"155.823032ms","hash":2073334399,"current-db-size-bytes":59764736,"current-db-size":"60 MB","current-db-size-in-use-bytes":32952320,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-11T02:07:23.674413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2073334399,"revision":33413,"compact-revision":31017} {"level":"info","ts":"2026-05-11T02:12:23.519923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35674} {"level":"info","ts":"2026-05-11T02:12:23.658621Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35674,"took":"134.897877ms","hash":3858574214,"current-db-size-bytes":60223488,"current-db-size":"60 MB","current-db-size-in-use-bytes":31928320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T02:12:23.65876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3858574214,"revision":35674,"compact-revision":33413} {"level":"info","ts":"2026-05-11T02:14:20.507245Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T02:14:20.51161Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-11T02:14:20.511708Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}