{"level":"warn","ts":"2026-05-19T13:28:45.35313Z","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-19T13:28:45.353235Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-19T13:28:45.35333Z","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-19T13:28:45.353348Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-19T13:28:45.353367Z","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-19T13:28:45.354082Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-05-19T13:28:45.355239Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-19T13:28:45.359422Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.936603ms"} {"level":"info","ts":"2026-05-19T13:28:45.367322Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-05-19T13:28:45.367464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T13:28:45.367513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-05-19T13:28:45.367534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T13:28:45.36756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-05-19T13:28:45.367619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-05-19T13:28:45.374459Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T13:28:45.377023Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T13:28:45.378299Z","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-19T13:28:45.381363Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T13:28:45.381823Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T13:28:45.381823Z","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-19T13:28:45.381968Z","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-19T13:28:45.381981Z","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-19T13:28:45.382607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-05-19T13:28:45.383762Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-19T13:28:45.387357Z","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-19T13:28:45.387534Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-19T13:28:45.387634Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-19T13:28:45.387713Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T13:28:45.387785Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T13:28:45.86815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T13:28:45.868249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T13:28:45.868308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-05-19T13:28:45.868334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-05-19T13:28:45.868351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-19T13:28:45.868393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-05-19T13:28:45.86841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-19T13:28:45.869793Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T13:28:45.869808Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:28:45.869842Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:28:45.869903Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:28:45.870293Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T13:28:45.870345Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T13:28:45.871053Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:28:45.871205Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:28:45.871252Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:28:45.874302Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-05-19T13:28:45.874693Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T13:28:58.545547Z","caller":"traceutil/trace.go:171","msg":"trace[567250176] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"135.142824ms","start":"2026-05-19T13:28:58.410358Z","end":"2026-05-19T13:28:58.545501Z","steps":["trace[567250176] 'process raft request' (duration: 54.872352ms)","trace[567250176] 'compare' (duration: 80.107928ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:28:58.761566Z","caller":"traceutil/trace.go:171","msg":"trace[79791874] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"156.533502ms","start":"2026-05-19T13:28:58.605003Z","end":"2026-05-19T13:28:58.761537Z","steps":["trace[79791874] 'process raft request' (duration: 153.52596ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:20.795966Z","caller":"traceutil/trace.go:171","msg":"trace[944965816] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"100.649894ms","start":"2026-05-19T13:29:20.695277Z","end":"2026-05-19T13:29:20.795927Z","steps":["trace[944965816] 'process raft request' (duration: 100.288906ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:20.999053Z","caller":"traceutil/trace.go:171","msg":"trace[1641176121] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"121.581929ms","start":"2026-05-19T13:29:20.877437Z","end":"2026-05-19T13:29:20.999019Z","steps":["trace[1641176121] 'process raft request' (duration: 121.526408ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:20.999085Z","caller":"traceutil/trace.go:171","msg":"trace[1184525250] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"128.045245ms","start":"2026-05-19T13:29:20.870994Z","end":"2026-05-19T13:29:20.999039Z","steps":["trace[1184525250] 'process raft request' (duration: 116.762119ms)","trace[1184525250] 'compare' (duration: 10.98949ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:29:20.99913Z","caller":"traceutil/trace.go:171","msg":"trace[690886656] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"123.879251ms","start":"2026-05-19T13:29:20.875214Z","end":"2026-05-19T13:29:20.999093Z","steps":["trace[690886656] 'read index received' (duration: 112.596415ms)","trace[690886656] 'applied index is now lower than readState.Index' (duration: 11.279336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:29:20.999445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.197378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-19T13:29:20.999566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.762534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-05-19T13:29:20.999598Z","caller":"traceutil/trace.go:171","msg":"trace[346690660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"124.435673ms","start":"2026-05-19T13:29:20.875139Z","end":"2026-05-19T13:29:20.999575Z","steps":["trace[346690660] 'agreement among raft nodes before linearized reading' (duration: 124.096916ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:20.999633Z","caller":"traceutil/trace.go:171","msg":"trace[388860597] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:629; }","duration":"114.896917ms","start":"2026-05-19T13:29:20.884715Z","end":"2026-05-19T13:29:20.999612Z","steps":["trace[388860597] 'agreement among raft nodes before linearized reading' (duration: 114.552249ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:27.240592Z","caller":"traceutil/trace.go:171","msg":"trace[1122401880] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"108.092482ms","start":"2026-05-19T13:29:27.132473Z","end":"2026-05-19T13:29:27.240565Z","steps":["trace[1122401880] 'process raft request' (duration: 83.494774ms)","trace[1122401880] 'compare' (duration: 24.491536ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:29:27.432665Z","caller":"traceutil/trace.go:171","msg":"trace[268195291] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"106.726891ms","start":"2026-05-19T13:29:27.325899Z","end":"2026-05-19T13:29:27.432626Z","steps":["trace[268195291] 'process raft request' (duration: 106.491246ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:27.63111Z","caller":"traceutil/trace.go:171","msg":"trace[1862389492] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"114.722532ms","start":"2026-05-19T13:29:27.51635Z","end":"2026-05-19T13:29:27.631072Z","steps":["trace[1862389492] 'process raft request' (duration: 114.510208ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:29:45.598451Z","caller":"traceutil/trace.go:171","msg":"trace[1754205865] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"151.071297ms","start":"2026-05-19T13:29:45.447352Z","end":"2026-05-19T13:29:45.598423Z","steps":["trace[1754205865] 'process raft request' (duration: 150.859733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:15.139765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.95716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:30:15.139896Z","caller":"traceutil/trace.go:171","msg":"trace[1127497253] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1207; }","duration":"169.152605ms","start":"2026-05-19T13:30:14.970716Z","end":"2026-05-19T13:30:15.139868Z","steps":["trace[1127497253] 'count revisions from in-memory index tree' (duration: 168.816267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:30:15.140252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.615755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T13:30:15.140459Z","caller":"traceutil/trace.go:171","msg":"trace[802771795] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1207; }","duration":"169.794458ms","start":"2026-05-19T13:30:14.970574Z","end":"2026-05-19T13:30:15.140369Z","steps":["trace[802771795] 'range keys from in-memory index tree' (duration: 169.191856ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:31:04.072809Z","caller":"traceutil/trace.go:171","msg":"trace[154435875] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"215.203722ms","start":"2026-05-19T13:31:03.857567Z","end":"2026-05-19T13:31:04.072771Z","steps":["trace[154435875] 'read index received' (duration: 215.042018ms)","trace[154435875] 'applied index is now lower than readState.Index' (duration: 160.064µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:31:04.073034Z","caller":"traceutil/trace.go:171","msg":"trace[152408390] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"283.207739ms","start":"2026-05-19T13:31:03.789762Z","end":"2026-05-19T13:31:04.07297Z","steps":["trace[152408390] 'process raft request' (duration: 282.815101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:31:04.073141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.557179ms","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-19T13:31:04.073263Z","caller":"traceutil/trace.go:171","msg":"trace[1561434866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1893; }","duration":"215.700192ms","start":"2026-05-19T13:31:03.857496Z","end":"2026-05-19T13:31:04.073196Z","steps":["trace[1561434866] 'agreement among raft nodes before linearized reading' (duration: 215.473297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:31:04.100091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.706374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-de98ef26-b261-433c-a630-f6ddfa0d414b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T13:31:04.100197Z","caller":"traceutil/trace.go:171","msg":"trace[153806795] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-de98ef26-b261-433c-a630-f6ddfa0d414b; range_end:; response_count:1; response_revision:1894; }","duration":"194.886088ms","start":"2026-05-19T13:31:03.905292Z","end":"2026-05-19T13:31:04.100178Z","steps":["trace[153806795] 'agreement among raft nodes before linearized reading' (duration: 194.573651ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:31:04.100081Z","caller":"traceutil/trace.go:171","msg":"trace[1275671779] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"178.036042ms","start":"2026-05-19T13:31:03.922001Z","end":"2026-05-19T13:31:04.100037Z","steps":["trace[1275671779] 'process raft request' (duration: 177.681004ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:31:04.243502Z","caller":"traceutil/trace.go:171","msg":"trace[1370698721] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"164.952751ms","start":"2026-05-19T13:31:04.078471Z","end":"2026-05-19T13:31:04.243424Z","steps":["trace[1370698721] 'process raft request' (duration: 81.170731ms)","trace[1370698721] 'compare' (duration: 83.555204ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:31:04.579524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.796425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T13:31:04.579687Z","caller":"traceutil/trace.go:171","msg":"trace[779847133] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1896; }","duration":"193.961889ms","start":"2026-05-19T13:31:04.385693Z","end":"2026-05-19T13:31:04.579655Z","steps":["trace[779847133] 'range keys from in-memory index tree' (duration: 193.537408ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:32:07.406557Z","caller":"traceutil/trace.go:171","msg":"trace[1501431527] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"189.294001ms","start":"2026-05-19T13:32:07.217219Z","end":"2026-05-19T13:32:07.406513Z","steps":["trace[1501431527] 'process raft request' (duration: 189.030225ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:32:07.406484Z","caller":"traceutil/trace.go:171","msg":"trace[1537812428] linearizableReadLoop","detail":"{readStateIndex:2369; appliedIndex:2368; }","duration":"132.38873ms","start":"2026-05-19T13:32:07.274066Z","end":"2026-05-19T13:32:07.406455Z","steps":["trace[1537812428] 'read index received' (duration: 132.182035ms)","trace[1537812428] 'applied index is now lower than readState.Index' (duration: 205.265µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:32:07.406985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.86535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T13:32:07.407068Z","caller":"traceutil/trace.go:171","msg":"trace[604408458] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2283; }","duration":"133.033774ms","start":"2026-05-19T13:32:07.274017Z","end":"2026-05-19T13:32:07.40705Z","steps":["trace[604408458] 'agreement among raft nodes before linearized reading' (duration: 132.621135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:32:07.924633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.702977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:32:07.924728Z","caller":"traceutil/trace.go:171","msg":"trace[76244908] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2286; }","duration":"128.883861ms","start":"2026-05-19T13:32:07.795824Z","end":"2026-05-19T13:32:07.924708Z","steps":["trace[76244908] 'count revisions from in-memory index tree' (duration: 128.536923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:32:14.107147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.524464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T13:32:14.107203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.211066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:32:14.107259Z","caller":"traceutil/trace.go:171","msg":"trace[791823612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2317; }","duration":"307.640276ms","start":"2026-05-19T13:32:13.799586Z","end":"2026-05-19T13:32:14.107226Z","steps":["trace[791823612] 'range keys from in-memory index tree' (duration: 307.273998ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:32:14.1073Z","caller":"traceutil/trace.go:171","msg":"trace[1165676952] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2317; }","duration":"149.38938ms","start":"2026-05-19T13:32:13.95789Z","end":"2026-05-19T13:32:14.10728Z","steps":["trace[1165676952] 'count revisions from in-memory index tree' (duration: 149.067842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:32:14.107393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:32:13.799499Z","time spent":"307.875112ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T13:32:14.30788Z","caller":"traceutil/trace.go:171","msg":"trace[1066958590] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"195.993371ms","start":"2026-05-19T13:32:14.111851Z","end":"2026-05-19T13:32:14.307845Z","steps":["trace[1066958590] 'process raft request' (duration: 195.745445ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:32:14.318929Z","caller":"traceutil/trace.go:171","msg":"trace[1187355292] linearizableReadLoop","detail":"{readStateIndex:2406; appliedIndex:2404; }","duration":"164.933987ms","start":"2026-05-19T13:32:14.153963Z","end":"2026-05-19T13:32:14.318897Z","steps":["trace[1187355292] 'read index received' (duration: 153.691296ms)","trace[1187355292] 'applied index is now lower than readState.Index' (duration: 11.241741ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:32:14.318989Z","caller":"traceutil/trace.go:171","msg":"trace[1214921020] transaction","detail":"{read_only:false; response_revision:2319; number_of_response:1; }","duration":"206.563556ms","start":"2026-05-19T13:32:14.112387Z","end":"2026-05-19T13:32:14.318951Z","steps":["trace[1214921020] 'process raft request' (duration: 205.650046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:32:14.319268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.285365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T13:32:14.319325Z","caller":"traceutil/trace.go:171","msg":"trace[669905109] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2319; }","duration":"165.362286ms","start":"2026-05-19T13:32:14.15395Z","end":"2026-05-19T13:32:14.319312Z","steps":["trace[669905109] 'agreement among raft nodes before linearized reading' (duration: 165.05568ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:33:22.520361Z","caller":"traceutil/trace.go:171","msg":"trace[1130534048] linearizableReadLoop","detail":"{readStateIndex:2995; appliedIndex:2994; }","duration":"362.811873ms","start":"2026-05-19T13:33:22.157488Z","end":"2026-05-19T13:33:22.5203Z","steps":["trace[1130534048] 'read index received' (duration: 316.156255ms)","trace[1130534048] 'applied index is now lower than readState.Index' (duration: 46.653838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:33:22.520572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.057228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:33:22.520625Z","caller":"traceutil/trace.go:171","msg":"trace[1081389207] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2886; }","duration":"363.175791ms","start":"2026-05-19T13:33:22.157437Z","end":"2026-05-19T13:33:22.520613Z","steps":["trace[1081389207] 'agreement among raft nodes before linearized reading' (duration: 363.055568ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:33:22.5206Z","caller":"traceutil/trace.go:171","msg":"trace[2120294144] transaction","detail":"{read_only:false; response_revision:2886; number_of_response:1; }","duration":"369.559041ms","start":"2026-05-19T13:33:22.150998Z","end":"2026-05-19T13:33:22.520557Z","steps":["trace[2120294144] 'process raft request' (duration: 322.620546ms)","trace[2120294144] 'compare' (duration: 45.923813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:33:22.520676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:33:22.157416Z","time spent":"363.250192ms","remote":"127.0.0.1:47564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T13:33:22.521182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:33:22.150978Z","time spent":"369.702003ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:33:22.597994Z","caller":"traceutil/trace.go:171","msg":"trace[240364345] transaction","detail":"{read_only:false; response_revision:2887; number_of_response:1; }","duration":"231.629082ms","start":"2026-05-19T13:33:22.36633Z","end":"2026-05-19T13:33:22.597959Z","steps":["trace[240364345] 'process raft request' (duration: 231.437868ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:36:35.878718Z","caller":"traceutil/trace.go:171","msg":"trace[696712480] transaction","detail":"{read_only:false; response_revision:4110; number_of_response:1; }","duration":"159.882839ms","start":"2026-05-19T13:36:35.718776Z","end":"2026-05-19T13:36:35.878659Z","steps":["trace[696712480] 'process raft request' (duration: 146.919435ms)","trace[696712480] 'compare' (duration: 12.78994ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:38:02.423874Z","caller":"traceutil/trace.go:171","msg":"trace[544019829] linearizableReadLoop","detail":"{readStateIndex:5473; appliedIndex:5472; }","duration":"211.282608ms","start":"2026-05-19T13:38:02.212563Z","end":"2026-05-19T13:38:02.423845Z","steps":["trace[544019829] 'read index received' (duration: 211.046983ms)","trace[544019829] 'applied index is now lower than readState.Index' (duration: 234.515µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:38:02.423986Z","caller":"traceutil/trace.go:171","msg":"trace[561755777] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"243.275141ms","start":"2026-05-19T13:38:02.180678Z","end":"2026-05-19T13:38:02.423953Z","steps":["trace[561755777] 'process raft request' (duration: 243.001485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:02.424103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.590683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:38:02.424146Z","caller":"traceutil/trace.go:171","msg":"trace[1544311426] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5277; }","duration":"175.680635ms","start":"2026-05-19T13:38:02.248453Z","end":"2026-05-19T13:38:02.424133Z","steps":["trace[1544311426] 'agreement among raft nodes before linearized reading' (duration: 175.563822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:02.424166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.595415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T13:38:02.42422Z","caller":"traceutil/trace.go:171","msg":"trace[2106309286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5277; }","duration":"211.662726ms","start":"2026-05-19T13:38:02.212543Z","end":"2026-05-19T13:38:02.424206Z","steps":["trace[2106309286] 'agreement among raft nodes before linearized reading' (duration: 211.467772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:02.424231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.071166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b05e990d-05d5-4fb7-90ae-1c9884468ab2\" ","response":"range_response_count:1 size:3721"} {"level":"warn","ts":"2026-05-19T13:38:02.424237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.99783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:38:02.424272Z","caller":"traceutil/trace.go:171","msg":"trace[1825603487] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b05e990d-05d5-4fb7-90ae-1c9884468ab2; range_end:; response_count:1; response_revision:5277; }","duration":"118.161608ms","start":"2026-05-19T13:38:02.3061Z","end":"2026-05-19T13:38:02.424262Z","steps":["trace[1825603487] 'agreement among raft nodes before linearized reading' (duration: 118.077977ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:02.424281Z","caller":"traceutil/trace.go:171","msg":"trace[496483254] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:5277; }","duration":"105.091743ms","start":"2026-05-19T13:38:02.319178Z","end":"2026-05-19T13:38:02.42427Z","steps":["trace[496483254] 'agreement among raft nodes before linearized reading' (duration: 104.9896ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:02.807143Z","caller":"traceutil/trace.go:171","msg":"trace[1918143685] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"102.79611ms","start":"2026-05-19T13:38:02.704306Z","end":"2026-05-19T13:38:02.807102Z","steps":["trace[1918143685] 'process raft request' (duration: 102.759299ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:02.807174Z","caller":"traceutil/trace.go:171","msg":"trace[1046599693] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"104.225203ms","start":"2026-05-19T13:38:02.702902Z","end":"2026-05-19T13:38:02.807127Z","steps":["trace[1046599693] 'process raft request' (duration: 104.080549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:14.526992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.850067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-4vv5h\" ","response":"range_response_count:1 size:9942"} {"level":"info","ts":"2026-05-19T13:38:14.527149Z","caller":"traceutil/trace.go:171","msg":"trace[2011307882] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-4vv5h; range_end:; response_count:1; response_revision:5516; }","duration":"216.050942ms","start":"2026-05-19T13:38:14.311074Z","end":"2026-05-19T13:38:14.527125Z","steps":["trace[2011307882] 'range keys from in-memory index tree' (duration: 215.615213ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:14.532678Z","caller":"traceutil/trace.go:171","msg":"trace[1513685660] linearizableReadLoop","detail":"{readStateIndex:5720; appliedIndex:5719; }","duration":"153.992238ms","start":"2026-05-19T13:38:14.378663Z","end":"2026-05-19T13:38:14.532655Z","steps":["trace[1513685660] 'read index received' (duration: 153.735642ms)","trace[1513685660] 'applied index is now lower than readState.Index' (duration: 255.516µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:38:14.53297Z","caller":"traceutil/trace.go:171","msg":"trace[1868277068] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"201.39357ms","start":"2026-05-19T13:38:14.331563Z","end":"2026-05-19T13:38:14.532957Z","steps":["trace[1868277068] 'process raft request' (duration: 200.899149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:14.533182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.504608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:38:14.53324Z","caller":"traceutil/trace.go:171","msg":"trace[808893894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5517; }","duration":"154.625881ms","start":"2026-05-19T13:38:14.378601Z","end":"2026-05-19T13:38:14.533227Z","steps":["trace[808893894] 'agreement among raft nodes before linearized reading' (duration: 154.521879ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:22.49362Z","caller":"traceutil/trace.go:171","msg":"trace[450827423] linearizableReadLoop","detail":"{readStateIndex:5888; appliedIndex:5887; }","duration":"114.892597ms","start":"2026-05-19T13:38:22.378701Z","end":"2026-05-19T13:38:22.493593Z","steps":["trace[450827423] 'read index received' (duration: 27.572167ms)","trace[450827423] 'applied index is now lower than readState.Index' (duration: 87.31884ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:38:22.493717Z","caller":"traceutil/trace.go:171","msg":"trace[1042999186] transaction","detail":"{read_only:false; response_revision:5681; number_of_response:1; }","duration":"134.947908ms","start":"2026-05-19T13:38:22.358728Z","end":"2026-05-19T13:38:22.493676Z","steps":["trace[1042999186] 'process raft request' (duration: 47.74737ms)","trace[1042999186] 'compare' (duration: 86.974013ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:38:22.493816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.099452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:38:22.493928Z","caller":"traceutil/trace.go:171","msg":"trace[1458188719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5681; }","duration":"115.271535ms","start":"2026-05-19T13:38:22.378625Z","end":"2026-05-19T13:38:22.493896Z","steps":["trace[1458188719] 'agreement among raft nodes before linearized reading' (duration: 115.062601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:38:22.493973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.003439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8448e19d-0004-4f85-b1e2-97e9cb1635ed\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-19T13:38:22.494058Z","caller":"traceutil/trace.go:171","msg":"trace[1661314628] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8448e19d-0004-4f85-b1e2-97e9cb1635ed; range_end:; response_count:1; response_revision:5681; }","duration":"105.145762ms","start":"2026-05-19T13:38:22.388893Z","end":"2026-05-19T13:38:22.494039Z","steps":["trace[1661314628] 'agreement among raft nodes before linearized reading' (duration: 104.892057ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:38:45.899241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3017} {"level":"info","ts":"2026-05-19T13:38:45.992062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3017,"took":"90.747625ms","hash":2030515307,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":29835264,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T13:38:45.992186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2030515307,"revision":3017,"compact-revision":-1} {"level":"info","ts":"2026-05-19T13:43:45.906988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5928} {"level":"info","ts":"2026-05-19T13:43:45.992624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5928,"took":"83.098841ms","hash":2295581363,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":25935872,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T13:43:45.992691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2295581363,"revision":5928,"compact-revision":3017} {"level":"info","ts":"2026-05-19T13:45:19.166168Z","caller":"traceutil/trace.go:171","msg":"trace[642689622] transaction","detail":"{read_only:false; response_revision:8786; number_of_response:1; }","duration":"173.568121ms","start":"2026-05-19T13:45:18.992543Z","end":"2026-05-19T13:45:19.166111Z","steps":["trace[642689622] 'process raft request' (duration: 173.238884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:19.166252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.87749ms","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-19T13:45:19.16637Z","caller":"traceutil/trace.go:171","msg":"trace[2117242625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8786; }","duration":"115.073174ms","start":"2026-05-19T13:45:19.051272Z","end":"2026-05-19T13:45:19.166345Z","steps":["trace[2117242625] 'agreement among raft nodes before linearized reading' (duration: 114.742317ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:45:20.845858Z","caller":"traceutil/trace.go:171","msg":"trace[501637967] transaction","detail":"{read_only:false; response_revision:8794; number_of_response:1; }","duration":"100.776103ms","start":"2026-05-19T13:45:20.745046Z","end":"2026-05-19T13:45:20.845822Z","steps":["trace[501637967] 'process raft request' (duration: 71.53379ms)","trace[501637967] 'compare' (duration: 29.09132ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:45:21.332461Z","caller":"traceutil/trace.go:171","msg":"trace[803557841] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"156.806458ms","start":"2026-05-19T13:45:21.175619Z","end":"2026-05-19T13:45:21.332425Z","steps":["trace[803557841] 'process raft request' (duration: 156.527572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:49.398799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.660749ms","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-19T13:45:49.398916Z","caller":"traceutil/trace.go:171","msg":"trace[1002232234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8998; }","duration":"393.810743ms","start":"2026-05-19T13:45:49.005078Z","end":"2026-05-19T13:45:49.398889Z","steps":["trace[1002232234] 'range keys from in-memory index tree' (duration: 393.515597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:49.398976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:45:49.005061Z","time spent":"393.901864ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T13:45:49.400225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.366112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T13:45:49.400287Z","caller":"traceutil/trace.go:171","msg":"trace[185520723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8998; }","duration":"147.462234ms","start":"2026-05-19T13:45:49.25281Z","end":"2026-05-19T13:45:49.400272Z","steps":["trace[185520723] 'range keys from in-memory index tree' (duration: 147.22412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:45:49.400573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.856465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-d8w4h\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-19T13:45:49.400625Z","caller":"traceutil/trace.go:171","msg":"trace[1878128518] range","detail":"{range_begin:/registry/pods/openstack/keepalived-d8w4h; range_end:; response_count:1; response_revision:8998; }","duration":"106.945197ms","start":"2026-05-19T13:45:49.293666Z","end":"2026-05-19T13:45:49.400611Z","steps":["trace[1878128518] 'range keys from in-memory index tree' (duration: 106.676091ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:45:53.088756Z","caller":"traceutil/trace.go:171","msg":"trace[1204429798] transaction","detail":"{read_only:false; response_revision:9017; number_of_response:1; }","duration":"213.719841ms","start":"2026-05-19T13:45:52.874995Z","end":"2026-05-19T13:45:53.088715Z","steps":["trace[1204429798] 'process raft request' (duration: 114.528712ms)","trace[1204429798] 'compare' (duration: 99.000375ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:45:53.131862Z","caller":"traceutil/trace.go:171","msg":"trace[375044763] transaction","detail":"{read_only:false; response_revision:9018; number_of_response:1; }","duration":"206.071794ms","start":"2026-05-19T13:45:52.925756Z","end":"2026-05-19T13:45:53.131828Z","steps":["trace[375044763] 'process raft request' (duration: 205.87513ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:46:02.706681Z","caller":"traceutil/trace.go:171","msg":"trace[377020426] transaction","detail":"{read_only:false; response_revision:9112; number_of_response:1; }","duration":"179.966799ms","start":"2026-05-19T13:46:02.526685Z","end":"2026-05-19T13:46:02.706651Z","steps":["trace[377020426] 'process raft request' (duration: 179.775634ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:46:02.866176Z","caller":"traceutil/trace.go:171","msg":"trace[777448231] transaction","detail":"{read_only:false; response_revision:9113; number_of_response:1; }","duration":"333.68461ms","start":"2026-05-19T13:46:02.532442Z","end":"2026-05-19T13:46:02.866126Z","steps":["trace[777448231] 'process raft request' (duration: 328.285163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:46:02.866617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:46:02.532417Z","time spent":"333.899465ms","remote":"127.0.0.1:35736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4635,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:46:03.500145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.990023ms","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:708"} {"level":"info","ts":"2026-05-19T13:46:03.500222Z","caller":"traceutil/trace.go:171","msg":"trace[314979572] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:9118; }","duration":"188.117216ms","start":"2026-05-19T13:46:03.312084Z","end":"2026-05-19T13:46:03.500202Z","steps":["trace[314979572] 'range keys from in-memory index tree' (duration: 187.793928ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:46:03.5117Z","caller":"traceutil/trace.go:171","msg":"trace[2083078405] transaction","detail":"{read_only:false; response_revision:9119; number_of_response:1; }","duration":"168.557593ms","start":"2026-05-19T13:46:03.3431Z","end":"2026-05-19T13:46:03.511658Z","steps":["trace[2083078405] 'process raft request' (duration: 168.37927ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:46:21.19955Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000258c.wal"} {"level":"info","ts":"2026-05-19T13:46:59.068194Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T13:46:59.076668Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T13:46:59.076848Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-19T13:47:22.215447Z","caller":"traceutil/trace.go:171","msg":"trace[249599730] linearizableReadLoop","detail":"{readStateIndex:10222; appliedIndex:10221; }","duration":"109.660743ms","start":"2026-05-19T13:47:22.105749Z","end":"2026-05-19T13:47:22.21541Z","steps":["trace[249599730] 'read index received' (duration: 72.921665ms)","trace[249599730] 'applied index is now lower than readState.Index' (duration: 36.737728ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:47:22.215521Z","caller":"traceutil/trace.go:171","msg":"trace[1056869150] transaction","detail":"{read_only:false; response_revision:9879; number_of_response:1; }","duration":"112.392582ms","start":"2026-05-19T13:47:22.103082Z","end":"2026-05-19T13:47:22.215474Z","steps":["trace[1056869150] 'process raft request' (duration: 75.594093ms)","trace[1056869150] 'compare' (duration: 36.533043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:47:22.215814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.090392ms","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-19T13:47:22.215914Z","caller":"traceutil/trace.go:171","msg":"trace[1484539270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9879; }","duration":"110.238746ms","start":"2026-05-19T13:47:22.105655Z","end":"2026-05-19T13:47:22.215894Z","steps":["trace[1484539270] 'agreement among raft nodes before linearized reading' (duration: 109.944889ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:48:45.914195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8093} {"level":"info","ts":"2026-05-19T13:48:45.982955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8093,"took":"66.259638ms","hash":201565753,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":27680768,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-19T13:48:45.983052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":201565753,"revision":8093,"compact-revision":5928} {"level":"info","ts":"2026-05-19T13:52:50.851285Z","caller":"traceutil/trace.go:171","msg":"trace[2119648626] linearizableReadLoop","detail":"{readStateIndex:12678; appliedIndex:12677; }","duration":"101.041251ms","start":"2026-05-19T13:52:50.75021Z","end":"2026-05-19T13:52:50.851251Z","steps":["trace[2119648626] 'read index received' (duration: 100.709704ms)","trace[2119648626] 'applied index is now lower than readState.Index' (duration: 329.357µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:52:50.851411Z","caller":"traceutil/trace.go:171","msg":"trace[235356859] transaction","detail":"{read_only:false; response_revision:12254; number_of_response:1; }","duration":"101.850218ms","start":"2026-05-19T13:52:50.749514Z","end":"2026-05-19T13:52:50.851364Z","steps":["trace[235356859] 'process raft request' (duration: 101.519981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:52:50.851656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.40961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:1136"} {"level":"info","ts":"2026-05-19T13:52:50.851708Z","caller":"traceutil/trace.go:171","msg":"trace[1312090218] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:12254; }","duration":"101.541442ms","start":"2026-05-19T13:52:50.750149Z","end":"2026-05-19T13:52:50.85169Z","steps":["trace[1312090218] 'agreement among raft nodes before linearized reading' (duration: 101.302027ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:53:08.249918Z","caller":"traceutil/trace.go:171","msg":"trace[151783507] linearizableReadLoop","detail":"{readStateIndex:12776; appliedIndex:12775; }","duration":"124.480235ms","start":"2026-05-19T13:53:08.125415Z","end":"2026-05-19T13:53:08.249895Z","steps":["trace[151783507] 'read index received' (duration: 74.081012ms)","trace[151783507] 'applied index is now lower than readState.Index' (duration: 50.398293ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:53:08.250048Z","caller":"traceutil/trace.go:171","msg":"trace[1147466071] transaction","detail":"{read_only:false; response_revision:12349; number_of_response:1; }","duration":"203.520463ms","start":"2026-05-19T13:53:08.046473Z","end":"2026-05-19T13:53:08.249993Z","steps":["trace[1147466071] 'process raft request' (duration: 153.10011ms)","trace[1147466071] 'compare' (duration: 49.972834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:53:08.250129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.701339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:53:08.250198Z","caller":"traceutil/trace.go:171","msg":"trace[622336508] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:12349; }","duration":"124.837942ms","start":"2026-05-19T13:53:08.125344Z","end":"2026-05-19T13:53:08.250182Z","steps":["trace[622336508] 'agreement among raft nodes before linearized reading' (duration: 124.674089ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:53:08.63887Z","caller":"traceutil/trace.go:171","msg":"trace[455490717] transaction","detail":"{read_only:false; response_revision:12353; number_of_response:1; }","duration":"201.388106ms","start":"2026-05-19T13:53:08.43743Z","end":"2026-05-19T13:53:08.638818Z","steps":["trace[455490717] 'process raft request' (duration: 188.459939ms)","trace[455490717] 'compare' (duration: 12.555589ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:53:09.824584Z","caller":"traceutil/trace.go:171","msg":"trace[1116907327] transaction","detail":"{read_only:false; response_revision:12358; number_of_response:1; }","duration":"158.538267ms","start":"2026-05-19T13:53:09.666013Z","end":"2026-05-19T13:53:09.824551Z","steps":["trace[1116907327] 'process raft request' (duration: 116.938653ms)","trace[1116907327] 'compare' (duration: 40.867267ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:53:45.921135Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10447} {"level":"info","ts":"2026-05-19T13:53:46.005432Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10447,"took":"81.177184ms","hash":2432380995,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":25563136,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T13:53:46.00552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2432380995,"revision":10447,"compact-revision":8093} {"level":"info","ts":"2026-05-19T13:58:45.92728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12603} {"level":"info","ts":"2026-05-19T13:58:46.013183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12603,"took":"83.466335ms","hash":1725771098,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":27504640,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-19T13:58:46.013253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1725771098,"revision":12603,"compact-revision":10447} {"level":"info","ts":"2026-05-19T14:00:16.50814Z","caller":"traceutil/trace.go:171","msg":"trace[1388086681] transaction","detail":"{read_only:false; response_revision:15162; number_of_response:1; }","duration":"166.218313ms","start":"2026-05-19T14:00:16.341865Z","end":"2026-05-19T14:00:16.508084Z","steps":["trace[1388086681] 'process raft request' (duration: 166.028479ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:01:18.98057Z","caller":"traceutil/trace.go:171","msg":"trace[2075512664] linearizableReadLoop","detail":"{readStateIndex:16227; appliedIndex:16226; }","duration":"123.124552ms","start":"2026-05-19T14:01:18.857423Z","end":"2026-05-19T14:01:18.980548Z","steps":["trace[2075512664] 'read index received' (duration: 123.00639ms)","trace[2075512664] 'applied index is now lower than readState.Index' (duration: 117.222µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:01:18.980695Z","caller":"traceutil/trace.go:171","msg":"trace[913835321] transaction","detail":"{read_only:false; response_revision:15674; number_of_response:1; }","duration":"129.272013ms","start":"2026-05-19T14:01:18.851394Z","end":"2026-05-19T14:01:18.980666Z","steps":["trace[913835321] 'process raft request' (duration: 128.948956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:01:18.980843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.405258ms","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-19T14:01:18.980904Z","caller":"traceutil/trace.go:171","msg":"trace[1099971768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15674; }","duration":"123.529561ms","start":"2026-05-19T14:01:18.857359Z","end":"2026-05-19T14:01:18.980889Z","steps":["trace[1099971768] 'agreement among raft nodes before linearized reading' (duration: 123.339827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:01:19.02193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.900802ms","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-19T14:01:19.022029Z","caller":"traceutil/trace.go:171","msg":"trace[1603536030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15675; }","duration":"162.028904ms","start":"2026-05-19T14:01:18.859976Z","end":"2026-05-19T14:01:19.022005Z","steps":["trace[1603536030] 'agreement among raft nodes before linearized reading' (duration: 161.762738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:01:19.022562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.191754ms","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-19T14:01:19.022622Z","caller":"traceutil/trace.go:171","msg":"trace[1390267298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15675; }","duration":"160.262626ms","start":"2026-05-19T14:01:18.862343Z","end":"2026-05-19T14:01:19.022606Z","steps":["trace[1390267298] 'agreement among raft nodes before linearized reading' (duration: 160.107742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:02:55.657218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.922324ms","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-19T14:02:55.657304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.480826ms","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-19T14:02:55.657392Z","caller":"traceutil/trace.go:171","msg":"trace[1405940837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16540; }","duration":"114.598319ms","start":"2026-05-19T14:02:55.542771Z","end":"2026-05-19T14:02:55.657369Z","steps":["trace[1405940837] 'range keys from in-memory index tree' (duration: 114.340964ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:02:55.657355Z","caller":"traceutil/trace.go:171","msg":"trace[733878999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16540; }","duration":"106.095637ms","start":"2026-05-19T14:02:55.551236Z","end":"2026-05-19T14:02:55.657331Z","steps":["trace[733878999] 'range keys from in-memory index tree' (duration: 105.7462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:02:55.657335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.878258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-19T14:02:55.657226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.051872ms","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-19T14:02:55.657739Z","caller":"traceutil/trace.go:171","msg":"trace[217934434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16540; }","duration":"110.632015ms","start":"2026-05-19T14:02:55.547091Z","end":"2026-05-19T14:02:55.657723Z","steps":["trace[217934434] 'range keys from in-memory index tree' (duration: 109.900629ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:02:55.657652Z","caller":"traceutil/trace.go:171","msg":"trace[1042383618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16540; }","duration":"110.232227ms","start":"2026-05-19T14:02:55.547395Z","end":"2026-05-19T14:02:55.657628Z","steps":["trace[1042383618] 'range keys from in-memory index tree' (duration: 109.723225ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:03:45.934579Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14618} {"level":"info","ts":"2026-05-19T14:03:46.018917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14618,"took":"81.114467ms","hash":1887718451,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":29278208,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T14:03:46.019022Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1887718451,"revision":14618,"compact-revision":12603} {"level":"info","ts":"2026-05-19T14:08:45.946452Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16849} {"level":"info","ts":"2026-05-19T14:08:46.04253Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16849,"took":"92.359121ms","hash":554038545,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":30654464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T14:08:46.042642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":554038545,"revision":16849,"compact-revision":14618} {"level":"info","ts":"2026-05-19T14:09:14.225116Z","caller":"traceutil/trace.go:171","msg":"trace[909721000] transaction","detail":"{read_only:false; response_revision:19305; number_of_response:1; }","duration":"446.627185ms","start":"2026-05-19T14:09:13.778457Z","end":"2026-05-19T14:09:14.225085Z","steps":["trace[909721000] 'process raft request' (duration: 446.450652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:09:14.225335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:09:13.778444Z","time spent":"446.775638ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:09:14.236848Z","caller":"traceutil/trace.go:171","msg":"trace[1554260428] linearizableReadLoop","detail":"{readStateIndex:19979; appliedIndex:19976; }","duration":"379.725027ms","start":"2026-05-19T14:09:13.857085Z","end":"2026-05-19T14:09:14.236811Z","steps":["trace[1554260428] 'read index received' (duration: 367.875244ms)","trace[1554260428] 'applied index is now lower than readState.Index' (duration: 11.848413ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:09:14.237239Z","caller":"traceutil/trace.go:171","msg":"trace[1797272113] transaction","detail":"{read_only:false; response_revision:19306; number_of_response:1; }","duration":"395.229468ms","start":"2026-05-19T14:09:13.841959Z","end":"2026-05-19T14:09:14.237188Z","steps":["trace[1797272113] 'process raft request' (duration: 394.618346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:09:14.237371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:09:13.841928Z","time spent":"395.340791ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:09:14.237699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.173633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5fx2d\" ","response":"range_response_count:1 size:20857"} {"level":"info","ts":"2026-05-19T14:09:14.237788Z","caller":"traceutil/trace.go:171","msg":"trace[1586337059] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-5fx2d; range_end:; response_count:1; response_revision:19307; }","duration":"377.326287ms","start":"2026-05-19T14:09:13.860442Z","end":"2026-05-19T14:09:14.237769Z","steps":["trace[1586337059] 'agreement among raft nodes before linearized reading' (duration: 377.065931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:09:14.23783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:09:13.860418Z","time spent":"377.403078ms","remote":"127.0.0.1:35736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20879,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5fx2d\" "} {"level":"info","ts":"2026-05-19T14:09:14.237902Z","caller":"traceutil/trace.go:171","msg":"trace[1192051864] transaction","detail":"{read_only:false; response_revision:19307; number_of_response:1; }","duration":"395.672178ms","start":"2026-05-19T14:09:13.842221Z","end":"2026-05-19T14:09:14.237894Z","steps":["trace[1192051864] 'process raft request' (duration: 394.532823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:09:14.237949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:09:13.842206Z","time spent":"395.71917ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:09:14.23805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.439508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T14:09:14.238073Z","caller":"traceutil/trace.go:171","msg":"trace[1887528852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19307; }","duration":"325.50727ms","start":"2026-05-19T14:09:13.912559Z","end":"2026-05-19T14:09:14.238066Z","steps":["trace[1887528852] 'agreement among raft nodes before linearized reading' (duration: 325.437938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:09:14.238091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:09:13.912536Z","time spent":"325.55077ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T14:09:14.23819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.661026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T14:09:14.23821Z","caller":"traceutil/trace.go:171","msg":"trace[414859347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19307; }","duration":"328.715648ms","start":"2026-05-19T14:09:13.909485Z","end":"2026-05-19T14:09:14.238201Z","steps":["trace[414859347] 'agreement among raft nodes before linearized reading' (duration: 328.657627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:09:14.23823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:09:13.909463Z","time spent":"328.76338ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T14:09:14.238309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.339184ms","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-19T14:09:14.238323Z","caller":"traceutil/trace.go:171","msg":"trace[1661516352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19307; }","duration":"331.399275ms","start":"2026-05-19T14:09:13.906919Z","end":"2026-05-19T14:09:14.238318Z","steps":["trace[1661516352] 'agreement among raft nodes before linearized reading' (duration: 331.350344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:09:14.238339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:09:13.906894Z","time spent":"331.441697ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T14:09:14.238393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.307091ms","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-19T14:09:14.238443Z","caller":"traceutil/trace.go:171","msg":"trace[1456725371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19307; }","duration":"381.399273ms","start":"2026-05-19T14:09:13.857034Z","end":"2026-05-19T14:09:14.238434Z","steps":["trace[1456725371] 'agreement among raft nodes before linearized reading' (duration: 381.29067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:09:14.238465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:09:13.857018Z","time spent":"381.442114ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T14:09:14.416829Z","caller":"traceutil/trace.go:171","msg":"trace[1797592088] linearizableReadLoop","detail":"{readStateIndex:19983; appliedIndex:19979; }","duration":"166.468315ms","start":"2026-05-19T14:09:14.250335Z","end":"2026-05-19T14:09:14.416804Z","steps":["trace[1797592088] 'read index received' (duration: 37.111592ms)","trace[1797592088] 'applied index is now lower than readState.Index' (duration: 129.355943ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:09:14.417237Z","caller":"traceutil/trace.go:171","msg":"trace[303058755] transaction","detail":"{read_only:false; response_revision:19309; number_of_response:1; }","duration":"172.083015ms","start":"2026-05-19T14:09:14.245142Z","end":"2026-05-19T14:09:14.417225Z","steps":["trace[303058755] 'process raft request' (duration: 171.293328ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:09:14.41743Z","caller":"traceutil/trace.go:171","msg":"trace[2089162773] transaction","detail":"{read_only:false; response_revision:19310; number_of_response:1; }","duration":"172.203957ms","start":"2026-05-19T14:09:14.245216Z","end":"2026-05-19T14:09:14.41742Z","steps":["trace[2089162773] 'process raft request' (duration: 171.414751ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:09:14.417519Z","caller":"traceutil/trace.go:171","msg":"trace[841801622] transaction","detail":"{read_only:false; response_revision:19311; number_of_response:1; }","duration":"167.317343ms","start":"2026-05-19T14:09:14.250194Z","end":"2026-05-19T14:09:14.417512Z","steps":["trace[841801622] 'process raft request' (duration: 166.489676ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:09:14.41774Z","caller":"traceutil/trace.go:171","msg":"trace[370796878] transaction","detail":"{read_only:false; response_revision:19312; number_of_response:1; }","duration":"163.312067ms","start":"2026-05-19T14:09:14.254418Z","end":"2026-05-19T14:09:14.41773Z","steps":["trace[370796878] 'process raft request' (duration: 162.310786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:09:14.418669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.313054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:953762"} {"level":"info","ts":"2026-05-19T14:09:14.418717Z","caller":"traceutil/trace.go:171","msg":"trace[204518034] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:19312; }","duration":"168.407695ms","start":"2026-05-19T14:09:14.250297Z","end":"2026-05-19T14:09:14.418705Z","steps":["trace[204518034] 'agreement among raft nodes before linearized reading' (duration: 167.565788ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:09:16.243452Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T14:09:16.249603Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T14:09:16.249702Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-19T14:13:45.954441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19131} {"level":"info","ts":"2026-05-19T14:13:46.057853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19131,"took":"99.037185ms","hash":2693405533,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":33828864,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T14:13:46.057914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2693405533,"revision":19131,"compact-revision":16849} {"level":"info","ts":"2026-05-19T14:15:25.98136Z","caller":"traceutil/trace.go:171","msg":"trace[1505517485] transaction","detail":"{read_only:false; response_revision:22178; number_of_response:1; }","duration":"158.475329ms","start":"2026-05-19T14:15:25.822857Z","end":"2026-05-19T14:15:25.981332Z","steps":["trace[1505517485] 'process raft request' (duration: 152.588513ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:15:31.994141Z","caller":"traceutil/trace.go:171","msg":"trace[777974826] transaction","detail":"{read_only:false; response_revision:22208; number_of_response:1; }","duration":"135.589789ms","start":"2026-05-19T14:15:31.858526Z","end":"2026-05-19T14:15:31.994116Z","steps":["trace[777974826] 'process raft request' (duration: 135.448646ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:15:32.275072Z","caller":"traceutil/trace.go:171","msg":"trace[914142648] transaction","detail":"{read_only:false; response_revision:22209; number_of_response:1; }","duration":"271.546674ms","start":"2026-05-19T14:15:32.003489Z","end":"2026-05-19T14:15:32.275036Z","steps":["trace[914142648] 'process raft request' (duration: 271.275698ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:15:32.64267Z","caller":"traceutil/trace.go:171","msg":"trace[1027354476] linearizableReadLoop","detail":"{readStateIndex:22979; appliedIndex:22978; }","duration":"264.669175ms","start":"2026-05-19T14:15:32.377965Z","end":"2026-05-19T14:15:32.642634Z","steps":["trace[1027354476] 'read index received' (duration: 264.518972ms)","trace[1027354476] 'applied index is now lower than readState.Index' (duration: 148.713µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:15:32.64281Z","caller":"traceutil/trace.go:171","msg":"trace[1079844052] transaction","detail":"{read_only:false; response_revision:22210; number_of_response:1; }","duration":"292.87091ms","start":"2026-05-19T14:15:32.349888Z","end":"2026-05-19T14:15:32.642759Z","steps":["trace[1079844052] 'process raft request' (duration: 292.497122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:15:32.642932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.931641ms","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-19T14:15:32.643054Z","caller":"traceutil/trace.go:171","msg":"trace[1804688956] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:22210; }","duration":"265.128745ms","start":"2026-05-19T14:15:32.377897Z","end":"2026-05-19T14:15:32.643025Z","steps":["trace[1804688956] 'agreement among raft nodes before linearized reading' (duration: 264.906771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:15:32.838556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.457595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:15:32.838791Z","caller":"traceutil/trace.go:171","msg":"trace[1594375217] linearizableReadLoop","detail":"{readStateIndex:22981; appliedIndex:22979; }","duration":"196.001504ms","start":"2026-05-19T14:15:32.642769Z","end":"2026-05-19T14:15:32.838771Z","steps":["trace[1594375217] 'read index received' (duration: 4.918236ms)","trace[1594375217] 'applied index is now lower than readState.Index' (duration: 191.082118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:15:32.838972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.150022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T14:15:32.839008Z","caller":"traceutil/trace.go:171","msg":"trace[758532505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22212; }","duration":"407.254763ms","start":"2026-05-19T14:15:32.431743Z","end":"2026-05-19T14:15:32.838997Z","steps":["trace[758532505] 'agreement among raft nodes before linearized reading' (duration: 407.08871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:15:32.839043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:15:32.431711Z","time spent":"407.322605ms","remote":"127.0.0.1:35824","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-19T14:15:32.839246Z","caller":"traceutil/trace.go:171","msg":"trace[910258878] transaction","detail":"{read_only:false; response_revision:22211; number_of_response:1; }","duration":"343.098938ms","start":"2026-05-19T14:15:32.496133Z","end":"2026-05-19T14:15:32.839232Z","steps":["trace[910258878] 'process raft request' (duration: 151.594242ms)","trace[910258878] 'compare' (duration: 189.883452ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:15:32.839308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:15:32.496106Z","time spent":"343.172609ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:15:32.839836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.193786ms","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-19T14:15:32.839847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.524234ms","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-19T14:15:32.839925Z","caller":"traceutil/trace.go:171","msg":"trace[1232450870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22212; }","duration":"243.315628ms","start":"2026-05-19T14:15:32.596588Z","end":"2026-05-19T14:15:32.839904Z","steps":["trace[1232450870] 'agreement among raft nodes before linearized reading' (duration: 243.033243ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:15:32.839947Z","caller":"traceutil/trace.go:171","msg":"trace[1501699257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22212; }","duration":"257.675657ms","start":"2026-05-19T14:15:32.58224Z","end":"2026-05-19T14:15:32.839916Z","steps":["trace[1501699257] 'agreement among raft nodes before linearized reading' (duration: 257.412201ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:15:32.839999Z","caller":"traceutil/trace.go:171","msg":"trace[1672901844] transaction","detail":"{read_only:false; response_revision:22212; number_of_response:1; }","duration":"343.741553ms","start":"2026-05-19T14:15:32.496218Z","end":"2026-05-19T14:15:32.83996Z","steps":["trace[1672901844] 'process raft request' (duration: 342.472535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:15:32.84016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.049255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-19T14:15:32.840191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:15:32.496178Z","time spent":"343.920897ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:15:32.840221Z","caller":"traceutil/trace.go:171","msg":"trace[626588030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22212; }","duration":"251.105706ms","start":"2026-05-19T14:15:32.589097Z","end":"2026-05-19T14:15:32.840203Z","steps":["trace[626588030] 'agreement among raft nodes before linearized reading' (duration: 250.942962ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:36.986869Z","caller":"traceutil/trace.go:171","msg":"trace[266420834] transaction","detail":"{read_only:false; response_revision:23586; number_of_response:1; }","duration":"129.259111ms","start":"2026-05-19T14:18:36.857575Z","end":"2026-05-19T14:18:36.986834Z","steps":["trace[266420834] 'process raft request' (duration: 129.019756ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:18:45.961172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21488} {"level":"info","ts":"2026-05-19T14:18:46.0822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21488,"took":"117.181193ms","hash":896298360,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":30507008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T14:18:46.082277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":896298360,"revision":21488,"compact-revision":19131} {"level":"info","ts":"2026-05-19T14:19:53.416771Z","caller":"traceutil/trace.go:171","msg":"trace[894920645] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"210.830271ms","start":"2026-05-19T14:19:53.205908Z","end":"2026-05-19T14:19:53.416738Z","steps":["trace[894920645] 'process raft request' (duration: 210.616236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:53.703414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.568716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:19:53.703652Z","caller":"traceutil/trace.go:171","msg":"trace[1582029427] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"444.316759ms","start":"2026-05-19T14:19:53.259299Z","end":"2026-05-19T14:19:53.703616Z","steps":["trace[1582029427] 'process raft request' (duration: 309.415596ms)","trace[1582029427] 'compare' (duration: 134.426243ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:53.703725Z","caller":"traceutil/trace.go:171","msg":"trace[1683083895] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"129.507547ms","start":"2026-05-19T14:19:53.574199Z","end":"2026-05-19T14:19:53.703706Z","steps":["trace[1683083895] 'process raft request' (duration: 129.462736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:53.703801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:53.259263Z","time spent":"444.460012ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:19:53.704082Z","caller":"traceutil/trace.go:171","msg":"trace[1630202041] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"387.97194ms","start":"2026-05-19T14:19:53.316099Z","end":"2026-05-19T14:19:53.704071Z","steps":["trace[1630202041] 'process raft request' (duration: 387.420399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:53.704142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:53.316068Z","time spent":"388.041172ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:19:53.704174Z","caller":"traceutil/trace.go:171","msg":"trace[1909341085] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"184.440046ms","start":"2026-05-19T14:19:53.519714Z","end":"2026-05-19T14:19:53.704154Z","steps":["trace[1909341085] 'process raft request' (duration: 183.914815ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:19:53.70417Z","caller":"traceutil/trace.go:171","msg":"trace[2064264303] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"338.201954ms","start":"2026-05-19T14:19:53.365931Z","end":"2026-05-19T14:19:53.704133Z","steps":["trace[2064264303] 'process raft request' (duration: 337.646852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:19:53.704376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:19:53.365887Z","time spent":"338.375967ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:19:53.704378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.432641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:19:53.704188Z","caller":"traceutil/trace.go:171","msg":"trace[194376577] linearizableReadLoop","detail":"{readStateIndex:25595; appliedIndex:25592; }","duration":"202.211247ms","start":"2026-05-19T14:19:53.501922Z","end":"2026-05-19T14:19:53.704133Z","steps":["trace[194376577] 'read index received' (duration: 66.761242ms)","trace[194376577] 'applied index is now lower than readState.Index' (duration: 135.446785ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:19:53.704438Z","caller":"traceutil/trace.go:171","msg":"trace[887069257] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24752; }","duration":"202.565634ms","start":"2026-05-19T14:19:53.501851Z","end":"2026-05-19T14:19:53.704416Z","steps":["trace[887069257] 'agreement among raft nodes before linearized reading' (duration: 202.3988ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:32.669753Z","caller":"traceutil/trace.go:171","msg":"trace[543753983] transaction","detail":"{read_only:false; response_revision:26934; number_of_response:1; }","duration":"130.738164ms","start":"2026-05-19T14:23:32.538995Z","end":"2026-05-19T14:23:32.669734Z","steps":["trace[543753983] 'process raft request' (duration: 130.571971ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:32.973871Z","caller":"traceutil/trace.go:171","msg":"trace[886459347] transaction","detail":"{read_only:false; response_revision:26935; number_of_response:1; }","duration":"312.999942ms","start":"2026-05-19T14:23:32.660828Z","end":"2026-05-19T14:23:32.973828Z","steps":["trace[886459347] 'process raft request' (duration: 277.334858ms)","trace[886459347] 'compare' (duration: 34.786376ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:32.974006Z","caller":"traceutil/trace.go:171","msg":"trace[71358541] transaction","detail":"{read_only:false; response_revision:26936; number_of_response:1; }","duration":"311.99422ms","start":"2026-05-19T14:23:32.661985Z","end":"2026-05-19T14:23:32.973979Z","steps":["trace[71358541] 'process raft request' (duration: 311.844727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:32.974081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:32.660765Z","time spent":"313.203437ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:32.974182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:32.661968Z","time spent":"312.118973ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:32.980443Z","caller":"traceutil/trace.go:171","msg":"trace[685474539] transaction","detail":"{read_only:false; response_revision:26937; number_of_response:1; }","duration":"310.587881ms","start":"2026-05-19T14:23:32.669835Z","end":"2026-05-19T14:23:32.980422Z","steps":["trace[685474539] 'process raft request' (duration: 310.344676ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:32.980689Z","caller":"traceutil/trace.go:171","msg":"trace[742165316] transaction","detail":"{read_only:false; response_revision:26938; number_of_response:1; }","duration":"130.53262ms","start":"2026-05-19T14:23:32.850117Z","end":"2026-05-19T14:23:32.98065Z","steps":["trace[742165316] 'process raft request' (duration: 130.176013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:32.980742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:32.6698Z","time spent":"310.709853ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:33.281783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.340029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:23:33.281961Z","caller":"traceutil/trace.go:171","msg":"trace[970293737] transaction","detail":"{read_only:false; response_revision:26940; number_of_response:1; }","duration":"254.007267ms","start":"2026-05-19T14:23:33.027926Z","end":"2026-05-19T14:23:33.281933Z","steps":["trace[970293737] 'process raft request' (duration: 107.384503ms)","trace[970293737] 'compare' (duration: 145.921189ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:33.523985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.03282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:23:33.524084Z","caller":"traceutil/trace.go:171","msg":"trace[67848714] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:26940; }","duration":"103.195123ms","start":"2026-05-19T14:23:33.420865Z","end":"2026-05-19T14:23:33.52406Z","steps":["trace[67848714] 'count revisions from in-memory index tree' (duration: 102.871336ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:33.880859Z","caller":"traceutil/trace.go:171","msg":"trace[496120741] linearizableReadLoop","detail":"{readStateIndex:27842; appliedIndex:27841; }","duration":"204.783462ms","start":"2026-05-19T14:23:33.676047Z","end":"2026-05-19T14:23:33.880831Z","steps":["trace[496120741] 'read index received' (duration: 204.68073ms)","trace[496120741] 'applied index is now lower than readState.Index' (duration: 101.632µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:33.88115Z","caller":"traceutil/trace.go:171","msg":"trace[377160971] transaction","detail":"{read_only:false; response_revision:26941; number_of_response:1; }","duration":"352.637633ms","start":"2026-05-19T14:23:33.528501Z","end":"2026-05-19T14:23:33.881138Z","steps":["trace[377160971] 'process raft request' (duration: 352.158413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:33.881269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:33.528478Z","time spent":"352.703294ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:33.881634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.554538ms","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-19T14:23:33.881749Z","caller":"traceutil/trace.go:171","msg":"trace[565500151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26941; }","duration":"205.727972ms","start":"2026-05-19T14:23:33.675994Z","end":"2026-05-19T14:23:33.881722Z","steps":["trace[565500151] 'agreement among raft nodes before linearized reading' (duration: 205.393935ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:33.90129Z","caller":"traceutil/trace.go:171","msg":"trace[1419652486] transaction","detail":"{read_only:false; response_revision:26942; number_of_response:1; }","duration":"154.749878ms","start":"2026-05-19T14:23:33.746516Z","end":"2026-05-19T14:23:33.901266Z","steps":["trace[1419652486] 'process raft request' (duration: 154.539633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:34.271855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.706656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:23:34.272279Z","caller":"traceutil/trace.go:171","msg":"trace[1282913399] transaction","detail":"{read_only:false; response_revision:26943; number_of_response:1; }","duration":"385.496788ms","start":"2026-05-19T14:23:33.886532Z","end":"2026-05-19T14:23:34.272029Z","steps":["trace[1282913399] 'process raft request' (duration: 273.427574ms)","trace[1282913399] 'compare' (duration: 110.862947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:34.272412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:33.886507Z","time spent":"385.839386ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:34.584931Z","caller":"traceutil/trace.go:171","msg":"trace[2118008540] transaction","detail":"{read_only:false; response_revision:26945; number_of_response:1; }","duration":"578.289481ms","start":"2026-05-19T14:23:34.006617Z","end":"2026-05-19T14:23:34.584907Z","steps":["trace[2118008540] 'process raft request' (duration: 578.201339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:34.585128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:34.006593Z","time spent":"578.445425ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:34.58512Z","caller":"traceutil/trace.go:171","msg":"trace[1606178761] transaction","detail":"{read_only:false; response_revision:26944; number_of_response:1; }","duration":"678.315028ms","start":"2026-05-19T14:23:33.906748Z","end":"2026-05-19T14:23:34.585063Z","steps":["trace[1606178761] 'process raft request' (duration: 664.232495ms)","trace[1606178761] 'compare' (duration: 13.671574ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:34.585248Z","caller":"traceutil/trace.go:171","msg":"trace[853303277] linearizableReadLoop","detail":"{readStateIndex:27845; appliedIndex:27843; }","duration":"649.885168ms","start":"2026-05-19T14:23:33.935323Z","end":"2026-05-19T14:23:34.585208Z","steps":["trace[853303277] 'read index received' (duration: 224.586337ms)","trace[853303277] 'applied index is now lower than readState.Index' (duration: 425.297081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:34.585349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:33.90672Z","time spent":"678.506801ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:34.585501Z","caller":"traceutil/trace.go:171","msg":"trace[1542868213] transaction","detail":"{read_only:false; response_revision:26946; number_of_response:1; }","duration":"576.759809ms","start":"2026-05-19T14:23:34.008727Z","end":"2026-05-19T14:23:34.585487Z","steps":["trace[1542868213] 'process raft request' (duration: 576.139575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:34.585638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:34.008715Z","time spent":"576.829171ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:34.585684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.202264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:23:34.585737Z","caller":"traceutil/trace.go:171","msg":"trace[548060755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26946; }","duration":"207.290525ms","start":"2026-05-19T14:23:34.378432Z","end":"2026-05-19T14:23:34.585722Z","steps":["trace[548060755] 'agreement among raft nodes before linearized reading' (duration: 207.188533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:34.585827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.853197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T14:23:34.585925Z","caller":"traceutil/trace.go:171","msg":"trace[1952662390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26946; }","duration":"650.01587ms","start":"2026-05-19T14:23:33.935886Z","end":"2026-05-19T14:23:34.585902Z","steps":["trace[1952662390] 'agreement among raft nodes before linearized reading' (duration: 649.737784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:34.585942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.584633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-19T14:23:34.585981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:33.93587Z","time spent":"650.101893ms","remote":"127.0.0.1:35824","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-19T14:23:34.58603Z","caller":"traceutil/trace.go:171","msg":"trace[1504960224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26946; }","duration":"650.740337ms","start":"2026-05-19T14:23:33.93527Z","end":"2026-05-19T14:23:34.586011Z","steps":["trace[1504960224] 'agreement among raft nodes before linearized reading' (duration: 650.335208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:34.586073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:33.935252Z","time spent":"650.812979ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T14:23:36.748911Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006ce5.wal"} {"level":"info","ts":"2026-05-19T14:23:40.22961Z","caller":"traceutil/trace.go:171","msg":"trace[793788914] transaction","detail":"{read_only:false; response_revision:26999; number_of_response:1; }","duration":"154.777049ms","start":"2026-05-19T14:23:40.074796Z","end":"2026-05-19T14:23:40.229573Z","steps":["trace[793788914] 'process raft request' (duration: 154.555484ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:40.655972Z","caller":"traceutil/trace.go:171","msg":"trace[1935645423] linearizableReadLoop","detail":"{readStateIndex:27903; appliedIndex:27902; }","duration":"418.70998ms","start":"2026-05-19T14:23:40.237232Z","end":"2026-05-19T14:23:40.655942Z","steps":["trace[1935645423] 'read index received' (duration: 418.671659ms)","trace[1935645423] 'applied index is now lower than readState.Index' (duration: 35.801µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:40.656485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.237951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11789"} {"level":"info","ts":"2026-05-19T14:23:40.656581Z","caller":"traceutil/trace.go:171","msg":"trace[201312811] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:27000; }","duration":"419.384894ms","start":"2026-05-19T14:23:40.237172Z","end":"2026-05-19T14:23:40.656556Z","steps":["trace[201312811] 'agreement among raft nodes before linearized reading' (duration: 419.15997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:40.656628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.237152Z","time spent":"419.466366ms","remote":"127.0.0.1:35964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11811,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-05-19T14:23:40.658554Z","caller":"traceutil/trace.go:171","msg":"trace[645727455] transaction","detail":"{read_only:false; response_revision:27000; number_of_response:1; }","duration":"580.402758ms","start":"2026-05-19T14:23:40.075692Z","end":"2026-05-19T14:23:40.656095Z","steps":["trace[645727455] 'process raft request' (duration: 580.061969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:40.658719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.075661Z","time spent":"582.959072ms","remote":"127.0.0.1:35834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:40.999824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.320912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:23:41.000057Z","caller":"traceutil/trace.go:171","msg":"trace[1529425922] transaction","detail":"{read_only:false; response_revision:27003; number_of_response:1; }","duration":"293.508284ms","start":"2026-05-19T14:23:40.706532Z","end":"2026-05-19T14:23:41.000041Z","steps":["trace[1529425922] 'process raft request' (duration: 293.469364ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:41.000064Z","caller":"traceutil/trace.go:171","msg":"trace[1069537925] transaction","detail":"{read_only:false; response_revision:27001; number_of_response:1; }","duration":"392.565539ms","start":"2026-05-19T14:23:40.607447Z","end":"2026-05-19T14:23:41.000013Z","steps":["trace[1069537925] 'process raft request' (duration: 256.973081ms)","trace[1069537925] 'compare' (duration: 134.860612ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:41.000098Z","caller":"traceutil/trace.go:171","msg":"trace[1106587298] transaction","detail":"{read_only:false; response_revision:27002; number_of_response:1; }","duration":"392.462976ms","start":"2026-05-19T14:23:40.60757Z","end":"2026-05-19T14:23:41.000033Z","steps":["trace[1106587298] 'process raft request' (duration: 392.365344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.000225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.607418Z","time spent":"392.723592ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:41.000378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.574282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T14:23:41.000418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.862164ms","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-19T14:23:41.000393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.607561Z","time spent":"392.666991ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:41.000418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.257208ms","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-19T14:23:41.00045Z","caller":"traceutil/trace.go:171","msg":"trace[1474948752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27003; }","duration":"696.930186ms","start":"2026-05-19T14:23:40.303509Z","end":"2026-05-19T14:23:41.000439Z","steps":["trace[1474948752] 'agreement among raft nodes before linearized reading' (duration: 696.774482ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:41.000459Z","caller":"traceutil/trace.go:171","msg":"trace[2092125191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27003; }","duration":"623.696585ms","start":"2026-05-19T14:23:40.376742Z","end":"2026-05-19T14:23:41.000439Z","steps":["trace[2092125191] 'agreement among raft nodes before linearized reading' (duration: 623.517391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.000476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.30349Z","time spent":"696.978456ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T14:23:41.000471Z","caller":"traceutil/trace.go:171","msg":"trace[1712746665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27003; }","duration":"389.352029ms","start":"2026-05-19T14:23:40.611105Z","end":"2026-05-19T14:23:41.000457Z","steps":["trace[1712746665] 'agreement among raft nodes before linearized reading' (duration: 389.124534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.000504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.376721Z","time spent":"623.774908ms","remote":"127.0.0.1:35558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T14:23:41.000511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.611095Z","time spent":"389.406852ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T14:23:41.000583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.265182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T14:23:41.00066Z","caller":"traceutil/trace.go:171","msg":"trace[1321216110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27003; }","duration":"384.370634ms","start":"2026-05-19T14:23:40.616271Z","end":"2026-05-19T14:23:41.000642Z","steps":["trace[1321216110] 'agreement among raft nodes before linearized reading' (duration: 384.104117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.000695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.616267Z","time spent":"384.422945ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T14:23:41.000074Z","caller":"traceutil/trace.go:171","msg":"trace[13974691] linearizableReadLoop","detail":"{readStateIndex:27905; appliedIndex:27903; }","duration":"343.985206ms","start":"2026-05-19T14:23:40.656056Z","end":"2026-05-19T14:23:41.000041Z","steps":["trace[13974691] 'read index received' (duration: 208.497281ms)","trace[13974691] 'applied index is now lower than readState.Index' (duration: 135.485565ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:41.000827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.501287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-19T14:23:41.000948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.982773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18b0fd870988512a\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-05-19T14:23:41.000954Z","caller":"traceutil/trace.go:171","msg":"trace[1690910836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27003; }","duration":"384.702161ms","start":"2026-05-19T14:23:40.616222Z","end":"2026-05-19T14:23:41.000924Z","steps":["trace[1690910836] 'agreement among raft nodes before linearized reading' (duration: 384.198369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.001015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.616212Z","time spent":"384.793003ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T14:23:41.001088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.851892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11789"} {"level":"info","ts":"2026-05-19T14:23:41.001146Z","caller":"traceutil/trace.go:171","msg":"trace[1190274097] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:27003; }","duration":"334.970834ms","start":"2026-05-19T14:23:40.666163Z","end":"2026-05-19T14:23:41.001134Z","steps":["trace[1190274097] 'agreement among raft nodes before linearized reading' (duration: 334.77378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.001181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.666131Z","time spent":"335.041696ms","remote":"127.0.0.1:35964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11811,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-19T14:23:41.001249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.197969ms","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-19T14:23:41.001319Z","caller":"traceutil/trace.go:171","msg":"trace[1738170935] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27003; }","duration":"183.319642ms","start":"2026-05-19T14:23:40.817967Z","end":"2026-05-19T14:23:41.001287Z","steps":["trace[1738170935] 'agreement among raft nodes before linearized reading' (duration: 183.197879ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:41.001632Z","caller":"traceutil/trace.go:171","msg":"trace[1535856398] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18b0fd870988512a; range_end:; response_count:1; response_revision:27003; }","duration":"763.683419ms","start":"2026-05-19T14:23:40.237939Z","end":"2026-05-19T14:23:41.000982Z","steps":["trace[1535856398] 'agreement among raft nodes before linearized reading' (duration: 762.916443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.001686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:40.237927Z","time spent":"763.74874ms","remote":"127.0.0.1:35632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":744,"request content":"key:\"/registry/events/openstack/container-infra-registry.18b0fd870988512a\" "} {"level":"warn","ts":"2026-05-19T14:23:41.348215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.100749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:23:41.348326Z","caller":"traceutil/trace.go:171","msg":"trace[1255022787] transaction","detail":"{read_only:false; response_revision:27004; number_of_response:1; }","duration":"344.1219ms","start":"2026-05-19T14:23:41.004185Z","end":"2026-05-19T14:23:41.348307Z","steps":["trace[1255022787] 'process raft request' (duration: 138.840317ms)","trace[1255022787] 'compare' (duration: 204.862964ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:41.348393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:41.004163Z","time spent":"344.198882ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:41.589547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.424829ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-19T14:23:41.589823Z","caller":"traceutil/trace.go:171","msg":"trace[663030613] transaction","detail":"{read_only:false; response_revision:27007; number_of_response:1; }","duration":"583.75616ms","start":"2026-05-19T14:23:41.006054Z","end":"2026-05-19T14:23:41.58981Z","steps":["trace[663030613] 'process raft request' (duration: 583.702769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.589902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:41.006048Z","time spent":"583.801821ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:41.589996Z","caller":"traceutil/trace.go:171","msg":"trace[2073917110] transaction","detail":"{read_only:false; response_revision:27005; number_of_response:1; }","duration":"584.934274ms","start":"2026-05-19T14:23:41.005054Z","end":"2026-05-19T14:23:41.589988Z","steps":["trace[2073917110] 'process raft request' (duration: 584.581637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.590027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:41.005034Z","time spent":"584.975065ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:41.590088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:41.004737Z","time spent":"585.344603ms","remote":"127.0.0.1:35632","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-19T14:23:41.590144Z","caller":"traceutil/trace.go:171","msg":"trace[1085193571] transaction","detail":"{read_only:false; response_revision:27006; number_of_response:1; }","duration":"584.147448ms","start":"2026-05-19T14:23:41.00599Z","end":"2026-05-19T14:23:41.590137Z","steps":["trace[1085193571] 'process raft request' (duration: 583.738939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.590179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:41.005977Z","time spent":"584.182748ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:41.590403Z","caller":"traceutil/trace.go:171","msg":"trace[1168994200] transaction","detail":"{read_only:false; response_revision:27008; number_of_response:1; }","duration":"583.8296ms","start":"2026-05-19T14:23:41.006553Z","end":"2026-05-19T14:23:41.590383Z","steps":["trace[1168994200] 'process raft request' (duration: 583.225327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.59052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:41.006546Z","time spent":"583.895931ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:41.590609Z","caller":"traceutil/trace.go:171","msg":"trace[936877112] transaction","detail":"{read_only:false; response_revision:27009; number_of_response:1; }","duration":"583.151045ms","start":"2026-05-19T14:23:41.00745Z","end":"2026-05-19T14:23:41.590601Z","steps":["trace[936877112] 'process raft request' (duration: 582.851199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.590649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:41.007443Z","time spent":"583.184876ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:41.590801Z","caller":"traceutil/trace.go:171","msg":"trace[567802854] transaction","detail":"{read_only:false; response_revision:27010; number_of_response:1; }","duration":"581.119052ms","start":"2026-05-19T14:23:41.009676Z","end":"2026-05-19T14:23:41.590795Z","steps":["trace[567802854] 'process raft request' (duration: 580.892538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.590834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:41.009669Z","time spent":"581.146323ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:41.590998Z","caller":"traceutil/trace.go:171","msg":"trace[414751639] transaction","detail":"{read_only:false; response_revision:27011; number_of_response:1; }","duration":"578.501325ms","start":"2026-05-19T14:23:41.01249Z","end":"2026-05-19T14:23:41.590991Z","steps":["trace[414751639] 'process raft request' (duration: 578.280291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.591037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:41.012481Z","time spent":"578.532837ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:41.591187Z","caller":"traceutil/trace.go:171","msg":"trace[424199110] linearizableReadLoop","detail":"{readStateIndex:27916; appliedIndex:27907; }","duration":"213.826925ms","start":"2026-05-19T14:23:41.377353Z","end":"2026-05-19T14:23:41.59118Z","steps":["trace[424199110] 'read index received' (duration: 87.68785ms)","trace[424199110] 'applied index is now lower than readState.Index' (duration: 126.138365ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:41.591297Z","caller":"traceutil/trace.go:171","msg":"trace[1181162569] transaction","detail":"{read_only:false; response_revision:27012; number_of_response:1; }","duration":"576.679887ms","start":"2026-05-19T14:23:41.014611Z","end":"2026-05-19T14:23:41.591291Z","steps":["trace[1181162569] 'process raft request' (duration: 576.35559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.5914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:41.0146Z","time spent":"576.710407ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:41.591486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.133962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:23:41.591503Z","caller":"traceutil/trace.go:171","msg":"trace[1430106024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27012; }","duration":"214.195623ms","start":"2026-05-19T14:23:41.377301Z","end":"2026-05-19T14:23:41.591497Z","steps":["trace[1430106024] 'agreement among raft nodes before linearized reading' (duration: 214.158882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.591931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.094671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274717"} {"level":"info","ts":"2026-05-19T14:23:41.591951Z","caller":"traceutil/trace.go:171","msg":"trace[153014262] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:27012; }","duration":"179.154123ms","start":"2026-05-19T14:23:41.412791Z","end":"2026-05-19T14:23:41.591945Z","steps":["trace[153014262] 'agreement among raft nodes before linearized reading' (duration: 178.804385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.592145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.039125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-19T14:23:41.592162Z","caller":"traceutil/trace.go:171","msg":"trace[1554913466] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:27012; }","duration":"134.098286ms","start":"2026-05-19T14:23:41.458059Z","end":"2026-05-19T14:23:41.592157Z","steps":["trace[1554913466] 'agreement among raft nodes before linearized reading' (duration: 134.041315ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:41.767227Z","caller":"traceutil/trace.go:171","msg":"trace[231883028] linearizableReadLoop","detail":"{readStateIndex:27918; appliedIndex:27917; }","duration":"157.192912ms","start":"2026-05-19T14:23:41.609997Z","end":"2026-05-19T14:23:41.76719Z","steps":["trace[231883028] 'read index received' (duration: 145.043881ms)","trace[231883028] 'applied index is now lower than readState.Index' (duration: 12.147291ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:41.767347Z","caller":"traceutil/trace.go:171","msg":"trace[62296] transaction","detail":"{read_only:false; response_revision:27014; number_of_response:1; }","duration":"163.835344ms","start":"2026-05-19T14:23:41.603445Z","end":"2026-05-19T14:23:41.76728Z","steps":["trace[62296] 'process raft request' (duration: 151.701414ms)","trace[62296] 'compare' (duration: 11.491176ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:41.767525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.369282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18b0fd865fe1cabf\" ","response":"range_response_count:1 size:695"} {"level":"warn","ts":"2026-05-19T14:23:41.767524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.514948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-05-19T14:23:41.767561Z","caller":"traceutil/trace.go:171","msg":"trace[1938265057] range","detail":"{range_begin:/registry/events/openstack/container-infra.18b0fd865fe1cabf; range_end:; response_count:1; response_revision:27014; }","duration":"155.453183ms","start":"2026-05-19T14:23:41.612096Z","end":"2026-05-19T14:23:41.76755Z","steps":["trace[1938265057] 'agreement among raft nodes before linearized reading' (duration: 155.323941ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:41.767565Z","caller":"traceutil/trace.go:171","msg":"trace[673210663] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27014; }","duration":"157.60566ms","start":"2026-05-19T14:23:41.609949Z","end":"2026-05-19T14:23:41.767555Z","steps":["trace[673210663] 'agreement among raft nodes before linearized reading' (duration: 157.437767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:41.975934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.049777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-19T14:23:41.976133Z","caller":"traceutil/trace.go:171","msg":"trace[1042306534] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:27015; }","duration":"107.279492ms","start":"2026-05-19T14:23:41.868742Z","end":"2026-05-19T14:23:41.976021Z","steps":["trace[1042306534] 'range keys from in-memory index tree' (duration: 106.888994ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.132875Z","caller":"traceutil/trace.go:171","msg":"trace[1538531559] transaction","detail":"{read_only:false; response_revision:27018; number_of_response:1; }","duration":"124.104242ms","start":"2026-05-19T14:23:43.008728Z","end":"2026-05-19T14:23:43.132832Z","steps":["trace[1538531559] 'process raft request' (duration: 123.830587ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.133021Z","caller":"traceutil/trace.go:171","msg":"trace[1936160860] transaction","detail":"{read_only:false; response_revision:27019; number_of_response:1; }","duration":"124.124023ms","start":"2026-05-19T14:23:43.008864Z","end":"2026-05-19T14:23:43.132988Z","steps":["trace[1936160860] 'process raft request' (duration: 123.894578ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.593121Z","caller":"traceutil/trace.go:171","msg":"trace[1815723286] transaction","detail":"{read_only:false; response_revision:27021; number_of_response:1; }","duration":"237.427762ms","start":"2026-05-19T14:23:43.355655Z","end":"2026-05-19T14:23:43.593083Z","steps":["trace[1815723286] 'process raft request' (duration: 237.176106ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.800279Z","caller":"traceutil/trace.go:171","msg":"trace[1836998955] transaction","detail":"{read_only:false; response_revision:27023; number_of_response:1; }","duration":"199.113481ms","start":"2026-05-19T14:23:43.601136Z","end":"2026-05-19T14:23:43.80025Z","steps":["trace[1836998955] 'process raft request' (duration: 198.62526ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.800698Z","caller":"traceutil/trace.go:171","msg":"trace[792246405] transaction","detail":"{read_only:false; response_revision:27026; number_of_response:1; }","duration":"197.432493ms","start":"2026-05-19T14:23:43.603236Z","end":"2026-05-19T14:23:43.800669Z","steps":["trace[792246405] 'process raft request' (duration: 197.342112ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.800802Z","caller":"traceutil/trace.go:171","msg":"trace[444420231] transaction","detail":"{read_only:false; response_revision:27024; number_of_response:1; }","duration":"199.457958ms","start":"2026-05-19T14:23:43.601335Z","end":"2026-05-19T14:23:43.800793Z","steps":["trace[444420231] 'process raft request' (duration: 198.841405ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.800713Z","caller":"traceutil/trace.go:171","msg":"trace[686336535] transaction","detail":"{read_only:false; response_revision:27025; number_of_response:1; }","duration":"197.542346ms","start":"2026-05-19T14:23:43.603124Z","end":"2026-05-19T14:23:43.800666Z","steps":["trace[686336535] 'process raft request' (duration: 197.348942ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.801026Z","caller":"traceutil/trace.go:171","msg":"trace[16596343] transaction","detail":"{read_only:false; response_revision:27027; number_of_response:1; }","duration":"197.72666ms","start":"2026-05-19T14:23:43.603288Z","end":"2026-05-19T14:23:43.801015Z","steps":["trace[16596343] 'process raft request' (duration: 197.322961ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.80111Z","caller":"traceutil/trace.go:171","msg":"trace[69069688] transaction","detail":"{read_only:false; response_revision:27028; number_of_response:1; }","duration":"197.056886ms","start":"2026-05-19T14:23:43.604008Z","end":"2026-05-19T14:23:43.801065Z","steps":["trace[69069688] 'process raft request' (duration: 196.905643ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.812206Z","caller":"traceutil/trace.go:171","msg":"trace[1484670696] transaction","detail":"{read_only:false; response_revision:27029; number_of_response:1; }","duration":"149.948195ms","start":"2026-05-19T14:23:43.662237Z","end":"2026-05-19T14:23:43.812186Z","steps":["trace[1484670696] 'process raft request' (duration: 149.778702ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:43.812483Z","caller":"traceutil/trace.go:171","msg":"trace[1486513036] linearizableReadLoop","detail":"{readStateIndex:27933; appliedIndex:27926; }","duration":"121.487515ms","start":"2026-05-19T14:23:43.690974Z","end":"2026-05-19T14:23:43.812461Z","steps":["trace[1486513036] 'read index received' (duration: 108.835974ms)","trace[1486513036] 'applied index is now lower than readState.Index' (duration: 12.650231ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:43.812852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.871544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-19T14:23:43.8129Z","caller":"traceutil/trace.go:171","msg":"trace[754062248] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:27030; }","duration":"121.994296ms","start":"2026-05-19T14:23:43.690894Z","end":"2026-05-19T14:23:43.812888Z","steps":["trace[754062248] 'agreement among raft nodes before linearized reading' (duration: 121.640639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:44.370055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.562104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:23:44.370216Z","caller":"traceutil/trace.go:171","msg":"trace[91147955] linearizableReadLoop","detail":"{readStateIndex:27936; appliedIndex:27935; }","duration":"302.159449ms","start":"2026-05-19T14:23:44.068037Z","end":"2026-05-19T14:23:44.370197Z","steps":["trace[91147955] 'read index received' (duration: 189.34732ms)","trace[91147955] 'applied index is now lower than readState.Index' (duration: 112.810329ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:44.370289Z","caller":"traceutil/trace.go:171","msg":"trace[2056313979] transaction","detail":"{read_only:false; response_revision:27032; number_of_response:1; }","duration":"323.904096ms","start":"2026-05-19T14:23:44.046341Z","end":"2026-05-19T14:23:44.370245Z","steps":["trace[2056313979] 'process raft request' (duration: 211.051446ms)","trace[2056313979] 'compare' (duration: 112.107894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:44.370912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:44.046318Z","time spent":"324.060849ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:44.37032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.271371ms","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-19T14:23:44.371055Z","caller":"traceutil/trace.go:171","msg":"trace[1062206007] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27032; }","duration":"303.036619ms","start":"2026-05-19T14:23:44.067973Z","end":"2026-05-19T14:23:44.371009Z","steps":["trace[1062206007] 'agreement among raft nodes before linearized reading' (duration: 302.281292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:44.371193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:44.067951Z","time spent":"303.193373ms","remote":"127.0.0.1:42464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-19T14:23:45.976089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23664} {"level":"info","ts":"2026-05-19T14:23:46.098173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23664,"took":"117.757485ms","hash":3390308051,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":43220992,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-19T14:23:46.098263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3390308051,"revision":23664,"compact-revision":21488} {"level":"warn","ts":"2026-05-19T14:23:53.065944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.997177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:23:53.066038Z","caller":"traceutil/trace.go:171","msg":"trace[1665436183] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:27095; }","duration":"468.168971ms","start":"2026-05-19T14:23:52.597848Z","end":"2026-05-19T14:23:53.066017Z","steps":["trace[1665436183] 'count revisions from in-memory index tree' (duration: 467.793383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:53.066082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:52.597814Z","time spent":"468.257294ms","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:23:53.066196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.182753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T14:23:53.066291Z","caller":"traceutil/trace.go:171","msg":"trace[183105489] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:27095; }","duration":"255.376568ms","start":"2026-05-19T14:23:52.810891Z","end":"2026-05-19T14:23:53.066267Z","steps":["trace[183105489] 'count revisions from in-memory index tree' (duration: 255.012299ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:53.313428Z","caller":"traceutil/trace.go:171","msg":"trace[71101563] linearizableReadLoop","detail":"{readStateIndex:28003; appliedIndex:28002; }","duration":"150.254112ms","start":"2026-05-19T14:23:53.163152Z","end":"2026-05-19T14:23:53.313406Z","steps":["trace[71101563] 'read index received' (duration: 150.017827ms)","trace[71101563] 'applied index is now lower than readState.Index' (duration: 235.585µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:53.313556Z","caller":"traceutil/trace.go:171","msg":"trace[1178111393] transaction","detail":"{read_only:false; response_revision:27096; number_of_response:1; }","duration":"197.550316ms","start":"2026-05-19T14:23:53.115956Z","end":"2026-05-19T14:23:53.313507Z","steps":["trace[1178111393] 'process raft request' (duration: 197.271401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:53.313665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.465036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:23:53.313779Z","caller":"traceutil/trace.go:171","msg":"trace[1668577717] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:27096; }","duration":"150.664461ms","start":"2026-05-19T14:23:53.163091Z","end":"2026-05-19T14:23:53.313755Z","steps":["trace[1668577717] 'agreement among raft nodes before linearized reading' (duration: 150.435886ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:53.364547Z","caller":"traceutil/trace.go:171","msg":"trace[1810925158] transaction","detail":"{read_only:false; response_revision:27097; number_of_response:1; }","duration":"187.610223ms","start":"2026-05-19T14:23:53.176906Z","end":"2026-05-19T14:23:53.364517Z","steps":["trace[1810925158] 'process raft request' (duration: 187.372868ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:53.364559Z","caller":"traceutil/trace.go:171","msg":"trace[1390459238] transaction","detail":"{read_only:false; response_revision:27098; number_of_response:1; }","duration":"183.530206ms","start":"2026-05-19T14:23:53.181003Z","end":"2026-05-19T14:23:53.364533Z","steps":["trace[1390459238] 'process raft request' (duration: 183.416443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:53.364909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.9567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-19T14:23:53.365202Z","caller":"traceutil/trace.go:171","msg":"trace[1817398300] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:27098; }","duration":"167.099173ms","start":"2026-05-19T14:23:53.197874Z","end":"2026-05-19T14:23:53.364973Z","steps":["trace[1817398300] 'agreement among raft nodes before linearized reading' (duration: 166.705985ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:53.695522Z","caller":"traceutil/trace.go:171","msg":"trace[438391755] linearizableReadLoop","detail":"{readStateIndex:28006; appliedIndex:28005; }","duration":"327.252298ms","start":"2026-05-19T14:23:53.368234Z","end":"2026-05-19T14:23:53.695486Z","steps":["trace[438391755] 'read index received' (duration: 325.673425ms)","trace[438391755] 'applied index is now lower than readState.Index' (duration: 1.576993ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:53.695841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.580385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-aaf424b4-65e1-4ac7-994c-a7ffef2d6912\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-05-19T14:23:53.695885Z","caller":"traceutil/trace.go:171","msg":"trace[2059652552] range","detail":"{range_begin:/registry/persistentvolumes/pvc-aaf424b4-65e1-4ac7-994c-a7ffef2d6912; range_end:; response_count:1; response_revision:27099; }","duration":"327.690777ms","start":"2026-05-19T14:23:53.368183Z","end":"2026-05-19T14:23:53.695874Z","steps":["trace[2059652552] 'agreement among raft nodes before linearized reading' (duration: 327.472573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:53.695921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:53.368156Z","time spent":"327.756629ms","remote":"127.0.0.1:35690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-aaf424b4-65e1-4ac7-994c-a7ffef2d6912\" "} {"level":"info","ts":"2026-05-19T14:23:53.696326Z","caller":"traceutil/trace.go:171","msg":"trace[1896026570] transaction","detail":"{read_only:false; response_revision:27099; number_of_response:1; }","duration":"348.763809ms","start":"2026-05-19T14:23:53.346803Z","end":"2026-05-19T14:23:53.695567Z","steps":["trace[1896026570] 'process raft request' (duration: 347.085884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:53.696513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:53.346725Z","time spent":"349.68388ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:54.155413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.936821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:23:54.155656Z","caller":"traceutil/trace.go:171","msg":"trace[2114674647] transaction","detail":"{read_only:false; response_revision:27102; number_of_response:1; }","duration":"455.179601ms","start":"2026-05-19T14:23:53.700457Z","end":"2026-05-19T14:23:54.155637Z","steps":["trace[2114674647] 'process raft request' (duration: 455.063029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.155754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:53.700451Z","time spent":"455.267124ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:54.155985Z","caller":"traceutil/trace.go:171","msg":"trace[1776127462] transaction","detail":"{read_only:false; response_revision:27101; number_of_response:1; }","duration":"456.298365ms","start":"2026-05-19T14:23:53.699672Z","end":"2026-05-19T14:23:54.15597Z","steps":["trace[1776127462] 'process raft request' (duration: 336.68649ms)","trace[1776127462] 'compare' (duration: 118.528781ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:54.156053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:53.699623Z","time spent":"456.397927ms","remote":"127.0.0.1:35632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1369,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:54.156159Z","caller":"traceutil/trace.go:171","msg":"trace[194036475] linearizableReadLoop","detail":"{readStateIndex:28012; appliedIndex:28007; }","duration":"280.172658ms","start":"2026-05-19T14:23:53.875955Z","end":"2026-05-19T14:23:54.156127Z","steps":["trace[194036475] 'read index received' (duration: 160.288917ms)","trace[194036475] 'applied index is now lower than readState.Index' (duration: 119.882441ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:54.156323Z","caller":"traceutil/trace.go:171","msg":"trace[1776131768] transaction","detail":"{read_only:false; response_revision:27103; number_of_response:1; }","duration":"312.052353ms","start":"2026-05-19T14:23:53.844255Z","end":"2026-05-19T14:23:54.156307Z","steps":["trace[1776131768] 'process raft request' (duration: 311.336557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.156466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:53.844217Z","time spent":"312.140205ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:54.156758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.816111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T14:23:54.156808Z","caller":"traceutil/trace.go:171","msg":"trace[1232337755] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27105; }","duration":"280.865712ms","start":"2026-05-19T14:23:53.875929Z","end":"2026-05-19T14:23:54.156795Z","steps":["trace[1232337755] 'agreement among raft nodes before linearized reading' (duration: 280.719009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.156875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.598617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T14:23:54.156922Z","caller":"traceutil/trace.go:171","msg":"trace[1672284943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27105; }","duration":"279.713889ms","start":"2026-05-19T14:23:53.877197Z","end":"2026-05-19T14:23:54.156911Z","steps":["trace[1672284943] 'agreement among raft nodes before linearized reading' (duration: 279.541425ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:54.156962Z","caller":"traceutil/trace.go:171","msg":"trace[1791316213] transaction","detail":"{read_only:false; response_revision:27104; number_of_response:1; }","duration":"299.052444ms","start":"2026-05-19T14:23:53.857899Z","end":"2026-05-19T14:23:54.156951Z","steps":["trace[1791316213] 'process raft request' (duration: 297.998251ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:54.157054Z","caller":"traceutil/trace.go:171","msg":"trace[1076260204] transaction","detail":"{read_only:false; response_revision:27105; number_of_response:1; }","duration":"296.658902ms","start":"2026-05-19T14:23:53.860387Z","end":"2026-05-19T14:23:54.157046Z","steps":["trace[1076260204] 'process raft request' (duration: 295.684691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.157187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.225031ms","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-19T14:23:54.157219Z","caller":"traceutil/trace.go:171","msg":"trace[893010201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27105; }","duration":"281.297982ms","start":"2026-05-19T14:23:53.875914Z","end":"2026-05-19T14:23:54.157212Z","steps":["trace[893010201] 'agreement among raft nodes before linearized reading' (duration: 281.20419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.157225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.784745ms","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-19T14:23:54.157255Z","caller":"traceutil/trace.go:171","msg":"trace[266646986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27105; }","duration":"276.839797ms","start":"2026-05-19T14:23:53.880406Z","end":"2026-05-19T14:23:54.157246Z","steps":["trace[266646986] 'agreement among raft nodes before linearized reading' (duration: 276.735645ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:54.640173Z","caller":"traceutil/trace.go:171","msg":"trace[1152714990] transaction","detail":"{read_only:false; response_revision:27106; number_of_response:1; }","duration":"477.565062ms","start":"2026-05-19T14:23:54.162574Z","end":"2026-05-19T14:23:54.640139Z","steps":["trace[1152714990] 'process raft request' (duration: 477.401728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.640366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.162547Z","time spent":"477.716715ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:54.657689Z","caller":"traceutil/trace.go:171","msg":"trace[1280178862] transaction","detail":"{read_only:false; response_revision:27108; number_of_response:1; }","duration":"495.028486ms","start":"2026-05-19T14:23:54.162642Z","end":"2026-05-19T14:23:54.65767Z","steps":["trace[1280178862] 'process raft request' (duration: 494.943705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.657859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.162615Z","time spent":"495.18688ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:54.658089Z","caller":"traceutil/trace.go:171","msg":"trace[32731056] transaction","detail":"{read_only:false; response_revision:27107; number_of_response:1; }","duration":"495.479256ms","start":"2026-05-19T14:23:54.162595Z","end":"2026-05-19T14:23:54.658075Z","steps":["trace[32731056] 'process raft request' (duration: 494.884093ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:54.658122Z","caller":"traceutil/trace.go:171","msg":"trace[1991720711] transaction","detail":"{read_only:false; response_revision:27109; number_of_response:1; }","duration":"492.704466ms","start":"2026-05-19T14:23:54.165393Z","end":"2026-05-19T14:23:54.658098Z","steps":["trace[1991720711] 'process raft request' (duration: 492.236517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.658202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.162564Z","time spent":"495.594359ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:54.658413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.165378Z","time spent":"492.88953ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:54.658875Z","caller":"traceutil/trace.go:171","msg":"trace[286159686] transaction","detail":"{read_only:false; response_revision:27110; number_of_response:1; }","duration":"293.951304ms","start":"2026-05-19T14:23:54.364908Z","end":"2026-05-19T14:23:54.658859Z","steps":["trace[286159686] 'process raft request' (duration: 293.076395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.966231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.485824ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T14:23:54.966342Z","caller":"traceutil/trace.go:171","msg":"trace[1648236688] linearizableReadLoop","detail":"{readStateIndex:28017; appliedIndex:28013; }","duration":"588.529682ms","start":"2026-05-19T14:23:54.377793Z","end":"2026-05-19T14:23:54.966323Z","steps":["trace[1648236688] 'read index received' (duration: 263.226365ms)","trace[1648236688] 'applied index is now lower than readState.Index' (duration: 325.301847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:23:54.966716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.923009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:23:54.966762Z","caller":"traceutil/trace.go:171","msg":"trace[1904960739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27110; }","duration":"589.025792ms","start":"2026-05-19T14:23:54.377724Z","end":"2026-05-19T14:23:54.96675Z","steps":["trace[1904960739] 'agreement among raft nodes before linearized reading' (duration: 588.93048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.966794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.377692Z","time spent":"589.094363ms","remote":"127.0.0.1:35558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T14:23:54.966854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.793651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T14:23:54.966868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.297035ms","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-19T14:23:54.966912Z","caller":"traceutil/trace.go:171","msg":"trace[1415582501] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:27110; }","duration":"411.911744ms","start":"2026-05-19T14:23:54.554987Z","end":"2026-05-19T14:23:54.966898Z","steps":["trace[1415582501] 'agreement among raft nodes before linearized reading' (duration: 411.805601ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:54.966918Z","caller":"traceutil/trace.go:171","msg":"trace[656168147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27110; }","duration":"545.393537ms","start":"2026-05-19T14:23:54.421512Z","end":"2026-05-19T14:23:54.966906Z","steps":["trace[656168147] 'agreement among raft nodes before linearized reading' (duration: 545.210483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.966954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.421491Z","time spent":"545.455698ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T14:23:54.966953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.554964Z","time spent":"411.979046ms","remote":"127.0.0.1:44522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:23:54.966967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.598904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-19T14:23:54.967049Z","caller":"traceutil/trace.go:171","msg":"trace[1467007928] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:27110; }","duration":"153.738997ms","start":"2026-05-19T14:23:54.813286Z","end":"2026-05-19T14:23:54.967025Z","steps":["trace[1467007928] 'agreement among raft nodes before linearized reading' (duration: 153.517393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.967037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.201859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-lrk8g.18b0fccef754dfa2\" ","response":"range_response_count:1 size:1395"} {"level":"info","ts":"2026-05-19T14:23:54.967138Z","caller":"traceutil/trace.go:171","msg":"trace[1071440464] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-lrk8g.18b0fccef754dfa2; range_end:; response_count:1; response_revision:27110; }","duration":"342.357142ms","start":"2026-05-19T14:23:54.624752Z","end":"2026-05-19T14:23:54.967109Z","steps":["trace[1071440464] 'agreement among raft nodes before linearized reading' (duration: 342.037285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:54.967199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.62473Z","time spent":"342.452594ms","remote":"127.0.0.1:35632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":1417,"request content":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-lrk8g.18b0fccef754dfa2\" "} {"level":"info","ts":"2026-05-19T14:23:55.445072Z","caller":"traceutil/trace.go:171","msg":"trace[410846756] transaction","detail":"{read_only:false; response_revision:27111; number_of_response:1; }","duration":"475.09881ms","start":"2026-05-19T14:23:54.96995Z","end":"2026-05-19T14:23:55.445049Z","steps":["trace[410846756] 'process raft request' (duration: 474.837714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:55.445253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.969927Z","time spent":"475.219812ms","remote":"127.0.0.1:35632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1377,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:55.807398Z","caller":"traceutil/trace.go:171","msg":"trace[511282558] transaction","detail":"{read_only:false; response_revision:27112; number_of_response:1; }","duration":"834.946926ms","start":"2026-05-19T14:23:54.972427Z","end":"2026-05-19T14:23:55.80733Z","steps":["trace[511282558] 'process raft request' (duration: 834.601939ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:55.807393Z","caller":"traceutil/trace.go:171","msg":"trace[883108167] transaction","detail":"{read_only:false; response_revision:27113; number_of_response:1; }","duration":"834.955616ms","start":"2026-05-19T14:23:54.972409Z","end":"2026-05-19T14:23:55.807364Z","steps":["trace[883108167] 'process raft request' (duration: 834.802483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:55.807559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.972404Z","time spent":"835.067448ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:55.807744Z","caller":"traceutil/trace.go:171","msg":"trace[404078456] transaction","detail":"{read_only:false; response_revision:27114; number_of_response:1; }","duration":"488.067917ms","start":"2026-05-19T14:23:55.319652Z","end":"2026-05-19T14:23:55.80772Z","steps":["trace[404078456] 'process raft request' (duration: 487.631997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:55.80791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:55.319603Z","time spent":"488.207ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:55.808003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:54.972363Z","time spent":"835.280623ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:23:55.808142Z","caller":"traceutil/trace.go:171","msg":"trace[1299856788] transaction","detail":"{read_only:false; response_revision:27116; number_of_response:1; }","duration":"436.988362ms","start":"2026-05-19T14:23:55.371114Z","end":"2026-05-19T14:23:55.808102Z","steps":["trace[1299856788] 'process raft request' (duration: 436.808778ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:55.808014Z","caller":"traceutil/trace.go:171","msg":"trace[1166082788] linearizableReadLoop","detail":"{readStateIndex:28022; appliedIndex:28018; }","duration":"440.472916ms","start":"2026-05-19T14:23:55.367524Z","end":"2026-05-19T14:23:55.807997Z","steps":["trace[1166082788] 'read index received' (duration: 77.326469ms)","trace[1166082788] 'applied index is now lower than readState.Index' (duration: 363.145457ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:23:55.808085Z","caller":"traceutil/trace.go:171","msg":"trace[1866264181] transaction","detail":"{read_only:false; response_revision:27115; number_of_response:1; }","duration":"437.834909ms","start":"2026-05-19T14:23:55.370239Z","end":"2026-05-19T14:23:55.808074Z","steps":["trace[1866264181] 'process raft request' (duration: 437.481482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:55.808379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:55.371108Z","time spent":"437.141535ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:55.808483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:55.370227Z","time spent":"438.189507ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:23:55.808182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.64565ms","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":"info","ts":"2026-05-19T14:23:55.808857Z","caller":"traceutil/trace.go:171","msg":"trace[325829071] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:27116; }","duration":"441.367886ms","start":"2026-05-19T14:23:55.367472Z","end":"2026-05-19T14:23:55.80884Z","steps":["trace[325829071] 'agreement among raft nodes before linearized reading' (duration: 440.62454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:23:55.808907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:23:55.367452Z","time spent":"441.438717ms","remote":"127.0.0.1:44274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T14:23:55.808915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.008859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:23:55.808959Z","caller":"traceutil/trace.go:171","msg":"trace[21211521] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:27117; }","duration":"179.104071ms","start":"2026-05-19T14:23:55.629841Z","end":"2026-05-19T14:23:55.808945Z","steps":["trace[21211521] 'agreement among raft nodes before linearized reading' (duration: 178.999449ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:23:55.809222Z","caller":"traceutil/trace.go:171","msg":"trace[1778842703] transaction","detail":"{read_only:false; response_revision:27117; number_of_response:1; }","duration":"104.604504ms","start":"2026-05-19T14:23:55.704268Z","end":"2026-05-19T14:23:55.808873Z","steps":["trace[1778842703] 'process raft request' (duration: 104.334038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:02.571092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.567477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T14:24:02.571202Z","caller":"traceutil/trace.go:171","msg":"trace[1275629248] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27236; }","duration":"148.71164ms","start":"2026-05-19T14:24:02.422463Z","end":"2026-05-19T14:24:02.571175Z","steps":["trace[1275629248] 'range keys from in-memory index tree' (duration: 148.364671ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:02.979043Z","caller":"traceutil/trace.go:171","msg":"trace[607466496] transaction","detail":"{read_only:false; response_revision:27238; number_of_response:1; }","duration":"271.537363ms","start":"2026-05-19T14:24:02.70747Z","end":"2026-05-19T14:24:02.979007Z","steps":["trace[607466496] 'process raft request' (duration: 198.350354ms)","trace[607466496] 'compare' (duration: 72.655548ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:24:02.986433Z","caller":"traceutil/trace.go:171","msg":"trace[413160370] transaction","detail":"{read_only:false; response_revision:27239; number_of_response:1; }","duration":"277.822218ms","start":"2026-05-19T14:24:02.708576Z","end":"2026-05-19T14:24:02.986398Z","steps":["trace[413160370] 'process raft request' (duration: 277.618404ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:02.986636Z","caller":"traceutil/trace.go:171","msg":"trace[477522594] transaction","detail":"{read_only:false; response_revision:27240; number_of_response:1; }","duration":"277.985021ms","start":"2026-05-19T14:24:02.708603Z","end":"2026-05-19T14:24:02.986588Z","steps":["trace[477522594] 'process raft request' (duration: 277.730386ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:03.580725Z","caller":"traceutil/trace.go:171","msg":"trace[326631689] linearizableReadLoop","detail":"{readStateIndex:28150; appliedIndex:28149; }","duration":"188.603024ms","start":"2026-05-19T14:24:03.392098Z","end":"2026-05-19T14:24:03.580701Z","steps":["trace[326631689] 'read index received' (duration: 188.367289ms)","trace[326631689] 'applied index is now lower than readState.Index' (duration: 235.025µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:03.580942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.809059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-45122761-b303-4cda-a565-8ce255280668\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-19T14:24:03.580929Z","caller":"traceutil/trace.go:171","msg":"trace[169441205] transaction","detail":"{read_only:false; response_revision:27241; number_of_response:1; }","duration":"190.527116ms","start":"2026-05-19T14:24:03.390348Z","end":"2026-05-19T14:24:03.580875Z","steps":["trace[169441205] 'process raft request' (duration: 190.205269ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:03.581005Z","caller":"traceutil/trace.go:171","msg":"trace[342096773] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-45122761-b303-4cda-a565-8ce255280668; range_end:; response_count:1; response_revision:27241; }","duration":"188.920072ms","start":"2026-05-19T14:24:03.392068Z","end":"2026-05-19T14:24:03.580988Z","steps":["trace[342096773] 'agreement among raft nodes before linearized reading' (duration: 188.754698ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:10.529366Z","caller":"traceutil/trace.go:171","msg":"trace[574021276] linearizableReadLoop","detail":"{readStateIndex:28237; appliedIndex:28234; }","duration":"263.889239ms","start":"2026-05-19T14:24:10.265456Z","end":"2026-05-19T14:24:10.529345Z","steps":["trace[574021276] 'read index received' (duration: 223.249538ms)","trace[574021276] 'applied index is now lower than readState.Index' (duration: 40.638841ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:10.529755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.65987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418324"} {"level":"info","ts":"2026-05-19T14:24:10.529779Z","caller":"traceutil/trace.go:171","msg":"trace[1520691590] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:27325; }","duration":"176.742562ms","start":"2026-05-19T14:24:10.353028Z","end":"2026-05-19T14:24:10.52977Z","steps":["trace[1520691590] 'agreement among raft nodes before linearized reading' (duration: 176.559357ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:10.529919Z","caller":"traceutil/trace.go:171","msg":"trace[57190926] transaction","detail":"{read_only:false; response_revision:27324; number_of_response:1; }","duration":"301.893824ms","start":"2026-05-19T14:24:10.227999Z","end":"2026-05-19T14:24:10.529892Z","steps":["trace[57190926] 'process raft request' (duration: 301.237349ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:10.529943Z","caller":"traceutil/trace.go:171","msg":"trace[922375580] transaction","detail":"{read_only:false; response_revision:27323; number_of_response:1; }","duration":"302.260892ms","start":"2026-05-19T14:24:10.227639Z","end":"2026-05-19T14:24:10.529899Z","steps":["trace[922375580] 'process raft request' (duration: 261.075719ms)","trace[922375580] 'compare' (duration: 40.127ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:24:10.529996Z","caller":"traceutil/trace.go:171","msg":"trace[645439312] transaction","detail":"{read_only:false; response_revision:27325; number_of_response:1; }","duration":"299.564344ms","start":"2026-05-19T14:24:10.23037Z","end":"2026-05-19T14:24:10.529934Z","steps":["trace[645439312] 'process raft request' (duration: 298.94301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:10.530041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:24:10.227612Z","time spent":"302.368084ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:24:10.530044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:24:10.22799Z","time spent":"301.987516ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:24:10.530333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.268597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/secretgen-controller/secretgen-controller-sa\" ","response":"range_response_count:1 size:231"} {"level":"info","ts":"2026-05-19T14:24:10.530365Z","caller":"traceutil/trace.go:171","msg":"trace[903858604] range","detail":"{range_begin:/registry/serviceaccounts/secretgen-controller/secretgen-controller-sa; range_end:; response_count:1; response_revision:27325; }","duration":"264.946282ms","start":"2026-05-19T14:24:10.26541Z","end":"2026-05-19T14:24:10.530356Z","steps":["trace[903858604] 'agreement among raft nodes before linearized reading' (duration: 264.162384ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:10.890556Z","caller":"traceutil/trace.go:171","msg":"trace[1318375333] transaction","detail":"{read_only:false; response_revision:27326; number_of_response:1; }","duration":"136.975877ms","start":"2026-05-19T14:24:10.753542Z","end":"2026-05-19T14:24:10.890518Z","steps":["trace[1318375333] 'process raft request' (duration: 136.788944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:11.16366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.687299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:24:11.163852Z","caller":"traceutil/trace.go:171","msg":"trace[798981460] linearizableReadLoop","detail":"{readStateIndex:28239; appliedIndex:28238; }","duration":"204.592448ms","start":"2026-05-19T14:24:10.959157Z","end":"2026-05-19T14:24:11.163749Z","steps":["trace[798981460] 'read index received' (duration: 83.874139ms)","trace[798981460] 'applied index is now lower than readState.Index' (duration: 120.716749ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:24:11.16384Z","caller":"traceutil/trace.go:171","msg":"trace[329791801] transaction","detail":"{read_only:false; response_revision:27327; number_of_response:1; }","duration":"268.09627ms","start":"2026-05-19T14:24:10.895683Z","end":"2026-05-19T14:24:11.163779Z","steps":["trace[329791801] 'process raft request' (duration: 147.196187ms)","trace[329791801] 'compare' (duration: 120.511475ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:11.163967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.808323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:24:11.164375Z","caller":"traceutil/trace.go:171","msg":"trace[727461607] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:27327; }","duration":"205.240922ms","start":"2026-05-19T14:24:10.95909Z","end":"2026-05-19T14:24:11.164331Z","steps":["trace[727461607] 'agreement among raft nodes before linearized reading' (duration: 204.820933ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:11.173409Z","caller":"traceutil/trace.go:171","msg":"trace[1149536950] transaction","detail":"{read_only:false; response_revision:27328; number_of_response:1; }","duration":"206.816826ms","start":"2026-05-19T14:24:10.966576Z","end":"2026-05-19T14:24:11.173393Z","steps":["trace[1149536950] 'process raft request' (duration: 206.337176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:11.173529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.430907ms","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-19T14:24:11.173584Z","caller":"traceutil/trace.go:171","msg":"trace[631798002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27328; }","duration":"158.546179ms","start":"2026-05-19T14:24:11.015026Z","end":"2026-05-19T14:24:11.173572Z","steps":["trace[631798002] 'agreement among raft nodes before linearized reading' (duration: 158.321155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:11.173706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.383794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T14:24:11.173806Z","caller":"traceutil/trace.go:171","msg":"trace[369658020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27328; }","duration":"150.485987ms","start":"2026-05-19T14:24:11.023284Z","end":"2026-05-19T14:24:11.17377Z","steps":["trace[369658020] 'agreement among raft nodes before linearized reading' (duration: 150.039307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:11.173742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.161702ms","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-19T14:24:11.173867Z","caller":"traceutil/trace.go:171","msg":"trace[627915191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27328; }","duration":"151.299124ms","start":"2026-05-19T14:24:11.022556Z","end":"2026-05-19T14:24:11.173855Z","steps":["trace[627915191] 'agreement among raft nodes before linearized reading' (duration: 150.840415ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:25.444774Z","caller":"traceutil/trace.go:171","msg":"trace[1678679359] transaction","detail":"{read_only:false; response_revision:27440; number_of_response:1; }","duration":"124.217745ms","start":"2026-05-19T14:24:25.320524Z","end":"2026-05-19T14:24:25.444742Z","steps":["trace[1678679359] 'process raft request' (duration: 124.038061ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:25.468086Z","caller":"traceutil/trace.go:171","msg":"trace[2126452682] linearizableReadLoop","detail":"{readStateIndex:28357; appliedIndex:28355; }","duration":"108.13129ms","start":"2026-05-19T14:24:25.359918Z","end":"2026-05-19T14:24:25.468049Z","steps":["trace[2126452682] 'read index received' (duration: 84.82263ms)","trace[2126452682] 'applied index is now lower than readState.Index' (duration: 23.30702ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:25.468479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.530927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:24:25.46862Z","caller":"traceutil/trace.go:171","msg":"trace[1349160441] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:27442; }","duration":"108.752123ms","start":"2026-05-19T14:24:25.359834Z","end":"2026-05-19T14:24:25.468586Z","steps":["trace[1349160441] 'agreement among raft nodes before linearized reading' (duration: 108.520298ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:25.468931Z","caller":"traceutil/trace.go:171","msg":"trace[1602348305] transaction","detail":"{read_only:false; response_revision:27442; number_of_response:1; }","duration":"147.82692ms","start":"2026-05-19T14:24:25.321089Z","end":"2026-05-19T14:24:25.468916Z","steps":["trace[1602348305] 'process raft request' (duration: 146.910681ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:25.469065Z","caller":"traceutil/trace.go:171","msg":"trace[1858324643] transaction","detail":"{read_only:false; response_revision:27441; number_of_response:1; }","duration":"148.067105ms","start":"2026-05-19T14:24:25.320989Z","end":"2026-05-19T14:24:25.469057Z","steps":["trace[1858324643] 'process raft request' (duration: 146.814838ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:25.753749Z","caller":"traceutil/trace.go:171","msg":"trace[1866824030] linearizableReadLoop","detail":"{readStateIndex:28358; appliedIndex:28357; }","duration":"213.869886ms","start":"2026-05-19T14:24:25.53985Z","end":"2026-05-19T14:24:25.75372Z","steps":["trace[1866824030] 'read index received' (duration: 196.593106ms)","trace[1866824030] 'applied index is now lower than readState.Index' (duration: 17.27505ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:25.754045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.158623ms","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-19T14:24:25.754116Z","caller":"traceutil/trace.go:171","msg":"trace[2045309648] 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:27443; }","duration":"214.309546ms","start":"2026-05-19T14:24:25.539789Z","end":"2026-05-19T14:24:25.754098Z","steps":["trace[2045309648] 'agreement among raft nodes before linearized reading' (duration: 214.157963ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:25.753846Z","caller":"traceutil/trace.go:171","msg":"trace[386864632] transaction","detail":"{read_only:false; response_revision:27443; number_of_response:1; }","duration":"263.773867ms","start":"2026-05-19T14:24:25.490025Z","end":"2026-05-19T14:24:25.753799Z","steps":["trace[386864632] 'process raft request' (duration: 246.488706ms)","trace[386864632] 'compare' (duration: 16.949423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:25.754552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.940545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:24:25.754648Z","caller":"traceutil/trace.go:171","msg":"trace[1572839129] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:27443; }","duration":"116.053448ms","start":"2026-05-19T14:24:25.638568Z","end":"2026-05-19T14:24:25.754621Z","steps":["trace[1572839129] 'agreement among raft nodes before linearized reading' (duration: 115.891354ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:26.346455Z","caller":"traceutil/trace.go:171","msg":"trace[1050637386] transaction","detail":"{read_only:false; response_revision:27444; number_of_response:1; }","duration":"357.547989ms","start":"2026-05-19T14:24:25.988834Z","end":"2026-05-19T14:24:26.346382Z","steps":["trace[1050637386] 'process raft request' (duration: 356.909194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:26.346639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:24:25.988808Z","time spent":"357.739262ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:24:26.360271Z","caller":"traceutil/trace.go:171","msg":"trace[1897046541] transaction","detail":"{read_only:false; response_revision:27445; number_of_response:1; }","duration":"346.47262ms","start":"2026-05-19T14:24:26.013766Z","end":"2026-05-19T14:24:26.360239Z","steps":["trace[1897046541] 'process raft request' (duration: 346.231225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:26.360469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:24:26.013743Z","time spent":"346.624515ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:24:26.360601Z","caller":"traceutil/trace.go:171","msg":"trace[2112979238] linearizableReadLoop","detail":"{readStateIndex:28362; appliedIndex:28358; }","duration":"313.965213ms","start":"2026-05-19T14:24:26.046614Z","end":"2026-05-19T14:24:26.360579Z","steps":["trace[2112979238] 'read index received' (duration: 299.291088ms)","trace[2112979238] 'applied index is now lower than readState.Index' (duration: 14.673145ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:24:26.360629Z","caller":"traceutil/trace.go:171","msg":"trace[2110882922] transaction","detail":"{read_only:false; response_revision:27446; number_of_response:1; }","duration":"343.312773ms","start":"2026-05-19T14:24:26.017268Z","end":"2026-05-19T14:24:26.360581Z","steps":["trace[2110882922] 'process raft request' (duration: 342.922994ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:26.360697Z","caller":"traceutil/trace.go:171","msg":"trace[321028006] transaction","detail":"{read_only:false; response_revision:27447; number_of_response:1; }","duration":"340.245108ms","start":"2026-05-19T14:24:26.020409Z","end":"2026-05-19T14:24:26.360654Z","steps":["trace[321028006] 'process raft request' (duration: 340.028813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:26.36083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:24:26.017256Z","time spent":"343.466547ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:24:26.360869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.067975ms","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-19T14:24:26.360902Z","caller":"traceutil/trace.go:171","msg":"trace[959767362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27448; }","duration":"313.130826ms","start":"2026-05-19T14:24:26.047762Z","end":"2026-05-19T14:24:26.360893Z","steps":["trace[959767362] 'agreement among raft nodes before linearized reading' (duration: 312.954662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:26.360898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:24:26.020378Z","time spent":"340.397401ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:24:26.360931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:24:26.047756Z","time spent":"313.168716ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T14:24:26.360925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.284559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:24:26.361022Z","caller":"traceutil/trace.go:171","msg":"trace[1306778501] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:27448; }","duration":"314.405363ms","start":"2026-05-19T14:24:26.046592Z","end":"2026-05-19T14:24:26.360998Z","steps":["trace[1306778501] 'agreement among raft nodes before linearized reading' (duration: 314.087426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:26.361033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.25123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-05-19T14:24:26.361079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:24:26.04651Z","time spent":"314.557685ms","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-05-19T14:24:26.361092Z","caller":"traceutil/trace.go:171","msg":"trace[1221886705] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27448; }","duration":"155.352992ms","start":"2026-05-19T14:24:26.205721Z","end":"2026-05-19T14:24:26.361074Z","steps":["trace[1221886705] 'agreement among raft nodes before linearized reading' (duration: 155.171929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:26.361121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.739402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-19T14:24:26.361154Z","caller":"traceutil/trace.go:171","msg":"trace[441680747] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:27448; }","duration":"191.817724ms","start":"2026-05-19T14:24:26.169329Z","end":"2026-05-19T14:24:26.361146Z","steps":["trace[441680747] 'agreement among raft nodes before linearized reading' (duration: 191.65271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:26.364369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.168353ms","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-19T14:24:26.364437Z","caller":"traceutil/trace.go:171","msg":"trace[1180747832] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:27448; }","duration":"131.242745ms","start":"2026-05-19T14:24:26.233177Z","end":"2026-05-19T14:24:26.36442Z","steps":["trace[1180747832] 'agreement among raft nodes before linearized reading' (duration: 127.543596ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:26.582977Z","caller":"traceutil/trace.go:171","msg":"trace[1375681795] transaction","detail":"{read_only:false; response_revision:27449; number_of_response:1; }","duration":"210.046414ms","start":"2026-05-19T14:24:26.372902Z","end":"2026-05-19T14:24:26.582948Z","steps":["trace[1375681795] 'process raft request' (duration: 202.322949ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:26.62287Z","caller":"traceutil/trace.go:171","msg":"trace[417857423] linearizableReadLoop","detail":"{readStateIndex:28365; appliedIndex:28363; }","duration":"247.412466ms","start":"2026-05-19T14:24:26.375435Z","end":"2026-05-19T14:24:26.622848Z","steps":["trace[417857423] 'read index received' (duration: 199.738984ms)","trace[417857423] 'applied index is now lower than readState.Index' (duration: 47.669992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:26.623057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.59769ms","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-19T14:24:26.623093Z","caller":"traceutil/trace.go:171","msg":"trace[1615696658] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:27450; }","duration":"247.689602ms","start":"2026-05-19T14:24:26.375391Z","end":"2026-05-19T14:24:26.623081Z","steps":["trace[1615696658] 'agreement among raft nodes before linearized reading' (duration: 247.542219ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:26.623394Z","caller":"traceutil/trace.go:171","msg":"trace[2052820506] transaction","detail":"{read_only:false; response_revision:27450; number_of_response:1; }","duration":"248.06105ms","start":"2026-05-19T14:24:26.375311Z","end":"2026-05-19T14:24:26.623372Z","steps":["trace[2052820506] 'process raft request' (duration: 247.413156ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:27.070745Z","caller":"traceutil/trace.go:171","msg":"trace[510209449] transaction","detail":"{read_only:false; response_revision:27454; number_of_response:1; }","duration":"261.350865ms","start":"2026-05-19T14:24:26.809328Z","end":"2026-05-19T14:24:27.070679Z","steps":["trace[510209449] 'process raft request' (duration: 261.10625ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:27.941713Z","caller":"traceutil/trace.go:171","msg":"trace[543648350] linearizableReadLoop","detail":"{readStateIndex:28374; appliedIndex:28373; }","duration":"170.642989ms","start":"2026-05-19T14:24:27.77103Z","end":"2026-05-19T14:24:27.941673Z","steps":["trace[543648350] 'read index received' (duration: 170.283982ms)","trace[543648350] 'applied index is now lower than readState.Index' (duration: 357.817µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:24:27.94187Z","caller":"traceutil/trace.go:171","msg":"trace[2085766191] transaction","detail":"{read_only:false; response_revision:27459; number_of_response:1; }","duration":"185.887726ms","start":"2026-05-19T14:24:27.755919Z","end":"2026-05-19T14:24:27.941807Z","steps":["trace[2085766191] 'process raft request' (duration: 185.499448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:27.942386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.547048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-19T14:24:27.942537Z","caller":"traceutil/trace.go:171","msg":"trace[112649436] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:27459; }","duration":"156.721712ms","start":"2026-05-19T14:24:27.785765Z","end":"2026-05-19T14:24:27.942487Z","steps":["trace[112649436] 'agreement among raft nodes before linearized reading' (duration: 156.331313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:27.942604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.556869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274717"} {"level":"info","ts":"2026-05-19T14:24:27.94267Z","caller":"traceutil/trace.go:171","msg":"trace[1873918612] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:27459; }","duration":"171.639681ms","start":"2026-05-19T14:24:27.77101Z","end":"2026-05-19T14:24:27.94265Z","steps":["trace[1873918612] 'agreement among raft nodes before linearized reading' (duration: 170.853464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:28.170772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.735028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-45122761-b303-4cda-a565-8ce255280668\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-19T14:24:28.170879Z","caller":"traceutil/trace.go:171","msg":"trace[1247488983] range","detail":"{range_begin:/registry/persistentvolumes/pvc-45122761-b303-4cda-a565-8ce255280668; range_end:; response_count:1; response_revision:27459; }","duration":"223.893272ms","start":"2026-05-19T14:24:27.946959Z","end":"2026-05-19T14:24:28.170852Z","steps":["trace[1247488983] 'range keys from in-memory index tree' (duration: 223.530134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:28.171026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.033537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:24:28.171191Z","caller":"traceutil/trace.go:171","msg":"trace[804437622] transaction","detail":"{read_only:false; response_revision:27460; number_of_response:1; }","duration":"220.750804ms","start":"2026-05-19T14:24:27.950423Z","end":"2026-05-19T14:24:28.171174Z","steps":["trace[804437622] 'compare' (duration: 211.69844ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:28.171913Z","caller":"traceutil/trace.go:171","msg":"trace[242229249] transaction","detail":"{read_only:false; response_revision:27461; number_of_response:1; }","duration":"221.399068ms","start":"2026-05-19T14:24:27.950481Z","end":"2026-05-19T14:24:28.17188Z","steps":["trace[242229249] 'process raft request' (duration: 220.630292ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:28.172141Z","caller":"traceutil/trace.go:171","msg":"trace[353746105] transaction","detail":"{read_only:false; response_revision:27463; number_of_response:1; }","duration":"215.690496ms","start":"2026-05-19T14:24:27.956437Z","end":"2026-05-19T14:24:28.172127Z","steps":["trace[353746105] 'process raft request' (duration: 215.555253ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:28.172158Z","caller":"traceutil/trace.go:171","msg":"trace[938712749] transaction","detail":"{read_only:false; response_revision:27462; number_of_response:1; }","duration":"221.170443ms","start":"2026-05-19T14:24:27.950935Z","end":"2026-05-19T14:24:28.172106Z","steps":["trace[938712749] 'process raft request' (duration: 220.677403ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:29.724469Z","caller":"traceutil/trace.go:171","msg":"trace[761009396] transaction","detail":"{read_only:false; response_revision:27483; number_of_response:1; }","duration":"125.331417ms","start":"2026-05-19T14:24:29.599114Z","end":"2026-05-19T14:24:29.724445Z","steps":["trace[761009396] 'process raft request' (duration: 125.081112ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:29.724843Z","caller":"traceutil/trace.go:171","msg":"trace[995338390] transaction","detail":"{read_only:false; response_revision:27484; number_of_response:1; }","duration":"124.264946ms","start":"2026-05-19T14:24:29.600539Z","end":"2026-05-19T14:24:29.724804Z","steps":["trace[995338390] 'process raft request' (duration: 123.845266ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:29.725089Z","caller":"traceutil/trace.go:171","msg":"trace[1086106453] transaction","detail":"{read_only:false; response_revision:27485; number_of_response:1; }","duration":"124.334817ms","start":"2026-05-19T14:24:29.600696Z","end":"2026-05-19T14:24:29.725031Z","steps":["trace[1086106453] 'process raft request' (duration: 124.044551ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:29.725239Z","caller":"traceutil/trace.go:171","msg":"trace[2062592747] transaction","detail":"{read_only:false; response_revision:27486; number_of_response:1; }","duration":"123.346046ms","start":"2026-05-19T14:24:29.601829Z","end":"2026-05-19T14:24:29.725175Z","steps":["trace[2062592747] 'process raft request' (duration: 123.08673ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:34.902518Z","caller":"traceutil/trace.go:171","msg":"trace[1501442652] transaction","detail":"{read_only:false; response_revision:27534; number_of_response:1; }","duration":"167.498112ms","start":"2026-05-19T14:24:34.734987Z","end":"2026-05-19T14:24:34.902486Z","steps":["trace[1501442652] 'process raft request' (duration: 167.435361ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:34.90293Z","caller":"traceutil/trace.go:171","msg":"trace[1957875343] transaction","detail":"{read_only:false; response_revision:27533; number_of_response:1; }","duration":"167.993543ms","start":"2026-05-19T14:24:34.734892Z","end":"2026-05-19T14:24:34.902886Z","steps":["trace[1957875343] 'process raft request' (duration: 167.40098ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:34.903212Z","caller":"traceutil/trace.go:171","msg":"trace[1528378426] transaction","detail":"{read_only:false; response_revision:27532; number_of_response:1; }","duration":"171.189631ms","start":"2026-05-19T14:24:34.732007Z","end":"2026-05-19T14:24:34.903197Z","steps":["trace[1528378426] 'process raft request' (duration: 77.100903ms)","trace[1528378426] 'compare' (duration: 92.936294ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:24:43.050586Z","caller":"traceutil/trace.go:171","msg":"trace[1879853975] transaction","detail":"{read_only:false; response_revision:27593; number_of_response:1; }","duration":"119.88184ms","start":"2026-05-19T14:24:42.930673Z","end":"2026-05-19T14:24:43.050555Z","steps":["trace[1879853975] 'process raft request' (duration: 119.686586ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:43.066287Z","caller":"traceutil/trace.go:171","msg":"trace[726844989] transaction","detail":"{read_only:false; response_revision:27594; number_of_response:1; }","duration":"134.388552ms","start":"2026-05-19T14:24:42.931861Z","end":"2026-05-19T14:24:43.06625Z","steps":["trace[726844989] 'process raft request' (duration: 134.097866ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:43.066481Z","caller":"traceutil/trace.go:171","msg":"trace[206313283] transaction","detail":"{read_only:false; response_revision:27595; number_of_response:1; }","duration":"132.927911ms","start":"2026-05-19T14:24:42.933492Z","end":"2026-05-19T14:24:43.06642Z","steps":["trace[206313283] 'process raft request' (duration: 132.679985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:48.662989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.920861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:24:48.663287Z","caller":"traceutil/trace.go:171","msg":"trace[332490055] transaction","detail":"{read_only:false; response_revision:27634; number_of_response:1; }","duration":"103.286745ms","start":"2026-05-19T14:24:48.559973Z","end":"2026-05-19T14:24:48.66326Z","steps":["trace[332490055] 'process raft request' (duration: 103.152562ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:48.663373Z","caller":"traceutil/trace.go:171","msg":"trace[1055965793] transaction","detail":"{read_only:false; response_revision:27633; number_of_response:1; }","duration":"218.362473ms","start":"2026-05-19T14:24:48.444959Z","end":"2026-05-19T14:24:48.663321Z","steps":["trace[1055965793] 'process raft request' (duration: 35.989202ms)","trace[1055965793] 'compare' (duration: 181.38181ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:24:48.962522Z","caller":"traceutil/trace.go:171","msg":"trace[1049907965] transaction","detail":"{read_only:false; response_revision:27635; number_of_response:1; }","duration":"402.258497ms","start":"2026-05-19T14:24:48.560211Z","end":"2026-05-19T14:24:48.962469Z","steps":["trace[1049907965] 'process raft request' (duration: 347.324808ms)","trace[1049907965] 'compare' (duration: 54.554011ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:24:48.962578Z","caller":"traceutil/trace.go:171","msg":"trace[146309392] transaction","detail":"{read_only:false; response_revision:27637; number_of_response:1; }","duration":"128.295881ms","start":"2026-05-19T14:24:48.834252Z","end":"2026-05-19T14:24:48.962548Z","steps":["trace[146309392] 'process raft request' (duration: 128.24807ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:48.962576Z","caller":"traceutil/trace.go:171","msg":"trace[272137719] linearizableReadLoop","detail":"{readStateIndex:28556; appliedIndex:28554; }","duration":"279.191638ms","start":"2026-05-19T14:24:48.683369Z","end":"2026-05-19T14:24:48.962561Z","steps":["trace[272137719] 'read index received' (duration: 224.28486ms)","trace[272137719] 'applied index is now lower than readState.Index' (duration: 54.905958ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:48.962723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:24:48.560173Z","time spent":"402.448521ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T14:24:48.962833Z","caller":"traceutil/trace.go:171","msg":"trace[412766375] transaction","detail":"{read_only:false; response_revision:27636; number_of_response:1; }","duration":"402.375059ms","start":"2026-05-19T14:24:48.560448Z","end":"2026-05-19T14:24:48.962823Z","steps":["trace[412766375] 'process raft request' (duration: 401.900189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:48.962983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T14:24:48.56042Z","time spent":"402.451351ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T14:24:48.963359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.922973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T14:24:48.963407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.387309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T14:24:48.963464Z","caller":"traceutil/trace.go:171","msg":"trace[973283851] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27637; }","duration":"277.487481ms","start":"2026-05-19T14:24:48.685963Z","end":"2026-05-19T14:24:48.963451Z","steps":["trace[973283851] 'agreement among raft nodes before linearized reading' (duration: 277.262187ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:48.963469Z","caller":"traceutil/trace.go:171","msg":"trace[1321002858] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:27637; }","duration":"280.152678ms","start":"2026-05-19T14:24:48.683293Z","end":"2026-05-19T14:24:48.963445Z","steps":["trace[1321002858] 'agreement among raft nodes before linearized reading' (duration: 279.853342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:48.963391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.562398ms","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-19T14:24:48.963604Z","caller":"traceutil/trace.go:171","msg":"trace[376163562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27637; }","duration":"170.820133ms","start":"2026-05-19T14:24:48.792755Z","end":"2026-05-19T14:24:48.963575Z","steps":["trace[376163562] 'agreement among raft nodes before linearized reading' (duration: 170.417305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:53.40634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.598593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-695f6f7999-wtr2p\" ","response":"range_response_count:1 size:8866"} {"level":"info","ts":"2026-05-19T14:24:53.406439Z","caller":"traceutil/trace.go:171","msg":"trace[1208740136] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-695f6f7999-wtr2p; range_end:; response_count:1; response_revision:27681; }","duration":"228.773077ms","start":"2026-05-19T14:24:53.177644Z","end":"2026-05-19T14:24:53.406417Z","steps":["trace[1208740136] 'range keys from in-memory index tree' (duration: 228.359787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:53.406494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.679322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-695f6f7999-wtr2p.18b0fd8ab3180698\" ","response":"range_response_count:1 size:855"} {"level":"info","ts":"2026-05-19T14:24:53.406623Z","caller":"traceutil/trace.go:171","msg":"trace[50832033] range","detail":"{range_begin:/registry/events/openstack/magnum-api-695f6f7999-wtr2p.18b0fd8ab3180698; range_end:; response_count:1; response_revision:27681; }","duration":"226.903956ms","start":"2026-05-19T14:24:53.179688Z","end":"2026-05-19T14:24:53.406592Z","steps":["trace[50832033] 'range keys from in-memory index tree' (duration: 226.394065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:53.406757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.585015ms","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-19T14:24:53.407498Z","caller":"traceutil/trace.go:171","msg":"trace[1964739920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27681; }","duration":"156.435504ms","start":"2026-05-19T14:24:53.251031Z","end":"2026-05-19T14:24:53.407466Z","steps":["trace[1964739920] 'range keys from in-memory index tree' (duration: 155.069465ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:58.812827Z","caller":"traceutil/trace.go:171","msg":"trace[1091745351] transaction","detail":"{read_only:false; response_revision:27719; number_of_response:1; }","duration":"112.512552ms","start":"2026-05-19T14:24:58.700284Z","end":"2026-05-19T14:24:58.812796Z","steps":["trace[1091745351] 'process raft request' (duration: 112.328528ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:58.978856Z","caller":"traceutil/trace.go:171","msg":"trace[495931398] transaction","detail":"{read_only:false; response_revision:27720; number_of_response:1; }","duration":"277.880349ms","start":"2026-05-19T14:24:58.700946Z","end":"2026-05-19T14:24:58.978827Z","steps":["trace[495931398] 'process raft request' (duration: 277.579962ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:58.978852Z","caller":"traceutil/trace.go:171","msg":"trace[1851225098] linearizableReadLoop","detail":"{readStateIndex:28642; appliedIndex:28640; }","duration":"167.552963ms","start":"2026-05-19T14:24:58.811257Z","end":"2026-05-19T14:24:58.97881Z","steps":["trace[1851225098] 'read index received' (duration: 1.422191ms)","trace[1851225098] 'applied index is now lower than readState.Index' (duration: 166.129292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:58.978962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.682587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:24:58.979003Z","caller":"traceutil/trace.go:171","msg":"trace[611341342] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:27720; }","duration":"167.801219ms","start":"2026-05-19T14:24:58.811192Z","end":"2026-05-19T14:24:58.978993Z","steps":["trace[611341342] 'agreement among raft nodes before linearized reading' (duration: 167.691057ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:59.067071Z","caller":"traceutil/trace.go:171","msg":"trace[751783568] transaction","detail":"{read_only:false; response_revision:27721; number_of_response:1; }","duration":"174.38163ms","start":"2026-05-19T14:24:58.892657Z","end":"2026-05-19T14:24:59.067038Z","steps":["trace[751783568] 'process raft request' (duration: 174.223786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:59.27998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.343737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:24:59.28084Z","caller":"traceutil/trace.go:171","msg":"trace[41976095] linearizableReadLoop","detail":"{readStateIndex:28646; appliedIndex:28643; }","duration":"252.686959ms","start":"2026-05-19T14:24:59.028127Z","end":"2026-05-19T14:24:59.280814Z","steps":["trace[41976095] 'read index received' (duration: 39.16273ms)","trace[41976095] 'applied index is now lower than readState.Index' (duration: 213.523189ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:24:59.280931Z","caller":"traceutil/trace.go:171","msg":"trace[728071105] transaction","detail":"{read_only:false; response_revision:27724; number_of_response:1; }","duration":"259.509546ms","start":"2026-05-19T14:24:59.02138Z","end":"2026-05-19T14:24:59.280889Z","steps":["trace[728071105] 'process raft request' (duration: 259.367593ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:59.280938Z","caller":"traceutil/trace.go:171","msg":"trace[1089532128] transaction","detail":"{read_only:false; response_revision:27723; number_of_response:1; }","duration":"260.019527ms","start":"2026-05-19T14:24:59.020854Z","end":"2026-05-19T14:24:59.280874Z","steps":["trace[1089532128] 'process raft request' (duration: 259.321952ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:59.281299Z","caller":"traceutil/trace.go:171","msg":"trace[1339057200] transaction","detail":"{read_only:false; response_revision:27722; number_of_response:1; }","duration":"297.840457ms","start":"2026-05-19T14:24:58.982776Z","end":"2026-05-19T14:24:59.280617Z","steps":["trace[1339057200] 'process raft request' (duration: 172.656562ms)","trace[1339057200] 'compare' (duration: 124.119943ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:59.281558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.268561ms","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-19T14:24:59.281628Z","caller":"traceutil/trace.go:171","msg":"trace[1806403876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27724; }","duration":"253.503826ms","start":"2026-05-19T14:24:59.028108Z","end":"2026-05-19T14:24:59.281612Z","steps":["trace[1806403876] 'agreement among raft nodes before linearized reading' (duration: 252.779961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:59.323978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.525279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:24:59.32408Z","caller":"traceutil/trace.go:171","msg":"trace[1836170678] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:27727; }","duration":"220.736414ms","start":"2026-05-19T14:24:59.103322Z","end":"2026-05-19T14:24:59.324058Z","steps":["trace[1836170678] 'agreement among raft nodes before linearized reading' (duration: 220.543409ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:59.324547Z","caller":"traceutil/trace.go:171","msg":"trace[286051897] transaction","detail":"{read_only:false; response_revision:27725; number_of_response:1; }","duration":"204.70276ms","start":"2026-05-19T14:24:59.119831Z","end":"2026-05-19T14:24:59.324534Z","steps":["trace[286051897] 'process raft request' (duration: 203.78913ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:59.324722Z","caller":"traceutil/trace.go:171","msg":"trace[1179088216] transaction","detail":"{read_only:false; response_revision:27726; number_of_response:1; }","duration":"203.192218ms","start":"2026-05-19T14:24:59.12152Z","end":"2026-05-19T14:24:59.324712Z","steps":["trace[1179088216] 'process raft request' (duration: 202.252187ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:59.324877Z","caller":"traceutil/trace.go:171","msg":"trace[684754635] transaction","detail":"{read_only:false; response_revision:27727; number_of_response:1; }","duration":"199.397716ms","start":"2026-05-19T14:24:59.125469Z","end":"2026-05-19T14:24:59.324866Z","steps":["trace[684754635] 'process raft request' (duration: 198.350603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:59.325196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.985408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T14:24:59.325239Z","caller":"traceutil/trace.go:171","msg":"trace[1148318682] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27727; }","duration":"158.0656ms","start":"2026-05-19T14:24:59.167164Z","end":"2026-05-19T14:24:59.325229Z","steps":["trace[1148318682] 'agreement among raft nodes before linearized reading' (duration: 157.860106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:59.567001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.822995ms","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-19T14:24:59.567095Z","caller":"traceutil/trace.go:171","msg":"trace[1453002470] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27728; }","duration":"109.976148ms","start":"2026-05-19T14:24:59.457096Z","end":"2026-05-19T14:24:59.567072Z","steps":["trace[1453002470] 'range keys from in-memory index tree' (duration: 109.747863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:24:59.802268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.066411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T14:24:59.802516Z","caller":"traceutil/trace.go:171","msg":"trace[559065633] transaction","detail":"{read_only:false; response_revision:27729; number_of_response:1; }","duration":"230.518224ms","start":"2026-05-19T14:24:59.571953Z","end":"2026-05-19T14:24:59.802472Z","steps":["trace[559065633] 'process raft request' (duration: 119.151346ms)","trace[559065633] 'compare' (duration: 110.927058ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:24:59.802559Z","caller":"traceutil/trace.go:171","msg":"trace[303701345] transaction","detail":"{read_only:false; response_revision:27730; number_of_response:1; }","duration":"148.75922ms","start":"2026-05-19T14:24:59.653776Z","end":"2026-05-19T14:24:59.802535Z","steps":["trace[303701345] 'process raft request' (duration: 148.614627ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:24:59.802736Z","caller":"traceutil/trace.go:171","msg":"trace[1808038107] linearizableReadLoop","detail":"{readStateIndex:28652; appliedIndex:28650; }","duration":"230.694927ms","start":"2026-05-19T14:24:59.572Z","end":"2026-05-19T14:24:59.802695Z","steps":["trace[1808038107] 'read index received' (duration: 80.462786ms)","trace[1808038107] 'applied index is now lower than readState.Index' (duration: 150.227741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:24:59.802879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.872841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:24:59.802922Z","caller":"traceutil/trace.go:171","msg":"trace[773603805] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27730; }","duration":"230.953023ms","start":"2026-05-19T14:24:59.571959Z","end":"2026-05-19T14:24:59.802912Z","steps":["trace[773603805] 'agreement among raft nodes before linearized reading' (duration: 230.82104ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:25:00.102255Z","caller":"traceutil/trace.go:171","msg":"trace[117078760] transaction","detail":"{read_only:false; response_revision:27731; number_of_response:1; }","duration":"206.401686ms","start":"2026-05-19T14:24:59.895815Z","end":"2026-05-19T14:25:00.102217Z","steps":["trace[117078760] 'process raft request' (duration: 169.067085ms)","trace[117078760] 'compare' (duration: 36.948013ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:25:00.114618Z","caller":"traceutil/trace.go:171","msg":"trace[1656483991] transaction","detail":"{read_only:false; response_revision:27732; number_of_response:1; }","duration":"216.870902ms","start":"2026-05-19T14:24:59.897711Z","end":"2026-05-19T14:25:00.114582Z","steps":["trace[1656483991] 'process raft request' (duration: 216.496794ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:25:00.114741Z","caller":"traceutil/trace.go:171","msg":"trace[1342631374] linearizableReadLoop","detail":"{readStateIndex:28656; appliedIndex:28653; }","duration":"141.899793ms","start":"2026-05-19T14:24:59.972805Z","end":"2026-05-19T14:25:00.114705Z","steps":["trace[1342631374] 'read index received' (duration: 92.190537ms)","trace[1342631374] 'applied index is now lower than readState.Index' (duration: 49.708216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T14:25:00.114953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.122739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:25:00.115147Z","caller":"traceutil/trace.go:171","msg":"trace[692203381] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:27733; }","duration":"142.408744ms","start":"2026-05-19T14:24:59.972717Z","end":"2026-05-19T14:25:00.115126Z","steps":["trace[692203381] 'agreement among raft nodes before linearized reading' (duration: 142.128728ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:25:00.115034Z","caller":"traceutil/trace.go:171","msg":"trace[47515972] transaction","detail":"{read_only:false; response_revision:27733; number_of_response:1; }","duration":"215.42381ms","start":"2026-05-19T14:24:59.899594Z","end":"2026-05-19T14:25:00.115018Z","steps":["trace[47515972] 'process raft request' (duration: 214.832167ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:25:00.235618Z","caller":"traceutil/trace.go:171","msg":"trace[1704355893] transaction","detail":"{read_only:false; response_revision:27734; number_of_response:1; }","duration":"127.667918ms","start":"2026-05-19T14:25:00.107919Z","end":"2026-05-19T14:25:00.235587Z","steps":["trace[1704355893] 'process raft request' (duration: 127.464184ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:25:00.463237Z","caller":"traceutil/trace.go:171","msg":"trace[760655759] linearizableReadLoop","detail":"{readStateIndex:28659; appliedIndex:28658; }","duration":"220.339195ms","start":"2026-05-19T14:25:00.242869Z","end":"2026-05-19T14:25:00.463208Z","steps":["trace[760655759] 'read index received' (duration: 220.13241ms)","trace[760655759] 'applied index is now lower than readState.Index' (duration: 205.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:25:00.463303Z","caller":"traceutil/trace.go:171","msg":"trace[1119681842] transaction","detail":"{read_only:false; response_revision:27736; number_of_response:1; }","duration":"222.015101ms","start":"2026-05-19T14:25:00.241244Z","end":"2026-05-19T14:25:00.463259Z","steps":["trace[1119681842] 'process raft request' (duration: 221.748265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:25:00.463495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.60206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T14:25:00.463548Z","caller":"traceutil/trace.go:171","msg":"trace[488536001] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:27736; }","duration":"220.708402ms","start":"2026-05-19T14:25:00.242823Z","end":"2026-05-19T14:25:00.463532Z","steps":["trace[488536001] 'agreement among raft nodes before linearized reading' (duration: 220.564089ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:25:00.472107Z","caller":"traceutil/trace.go:171","msg":"trace[601060372] transaction","detail":"{read_only:false; response_revision:27737; number_of_response:1; }","duration":"227.895187ms","start":"2026-05-19T14:25:00.244184Z","end":"2026-05-19T14:25:00.472079Z","steps":["trace[601060372] 'process raft request' (duration: 227.540139ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:27:10.843738Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T14:27:10.850369Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-19T14:27:10.850972Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-19T14:28:30.45669Z","caller":"traceutil/trace.go:171","msg":"trace[1911099951] transaction","detail":"{read_only:false; response_revision:29844; number_of_response:1; }","duration":"197.029445ms","start":"2026-05-19T14:28:30.259638Z","end":"2026-05-19T14:28:30.456668Z","steps":["trace[1911099951] 'process raft request' (duration: 196.854192ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:28:30.457033Z","caller":"traceutil/trace.go:171","msg":"trace[543024305] transaction","detail":"{read_only:false; response_revision:29845; number_of_response:1; }","duration":"194.406329ms","start":"2026-05-19T14:28:30.262616Z","end":"2026-05-19T14:28:30.457022Z","steps":["trace[543024305] 'process raft request' (duration: 194.021211ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:28:30.457127Z","caller":"traceutil/trace.go:171","msg":"trace[1175772707] linearizableReadLoop","detail":"{readStateIndex:30819; appliedIndex:30815; }","duration":"186.456978ms","start":"2026-05-19T14:28:30.270641Z","end":"2026-05-19T14:28:30.457098Z","steps":["trace[1175772707] 'read index received' (duration: 45.650769ms)","trace[1175772707] 'applied index is now lower than readState.Index' (duration: 140.804869ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T14:28:30.457181Z","caller":"traceutil/trace.go:171","msg":"trace[1638360743] transaction","detail":"{read_only:false; response_revision:29846; number_of_response:1; }","duration":"194.413019ms","start":"2026-05-19T14:28:30.262744Z","end":"2026-05-19T14:28:30.457157Z","steps":["trace[1638360743] 'process raft request' (duration: 194.158493ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:28:30.457251Z","caller":"traceutil/trace.go:171","msg":"trace[433726132] transaction","detail":"{read_only:false; response_revision:29847; number_of_response:1; }","duration":"145.122461ms","start":"2026-05-19T14:28:30.312069Z","end":"2026-05-19T14:28:30.457191Z","steps":["trace[433726132] 'process raft request' (duration: 144.969288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T14:28:30.457432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.786265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4860"} {"level":"info","ts":"2026-05-19T14:28:30.457471Z","caller":"traceutil/trace.go:171","msg":"trace[2116835635] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:29847; }","duration":"186.890667ms","start":"2026-05-19T14:28:30.270572Z","end":"2026-05-19T14:28:30.457463Z","steps":["trace[2116835635] 'agreement among raft nodes before linearized reading' (duration: 186.692473ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T14:28:45.988139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27050} {"level":"info","ts":"2026-05-19T14:28:46.179254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27050,"took":"186.852628ms","hash":3468512933,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":35713024,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-19T14:28:46.179316Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3468512933,"revision":27050,"compact-revision":23664} {"level":"info","ts":"2026-05-19T14:33:45.996094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30034} {"level":"info","ts":"2026-05-19T14:33:46.14243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30034,"took":"140.042981ms","hash":3653121757,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":32239616,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T14:33:46.14251Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3653121757,"revision":30034,"compact-revision":27050} {"level":"info","ts":"2026-05-19T14:38:46.003019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32361} {"level":"info","ts":"2026-05-19T14:38:46.170476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32361,"took":"162.226457ms","hash":1094509194,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":30822400,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T14:38:46.170581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1094509194,"revision":32361,"compact-revision":30034} {"level":"info","ts":"2026-05-19T14:43:46.011201Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34599} {"level":"info","ts":"2026-05-19T14:43:46.138725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34599,"took":"123.078801ms","hash":1490995828,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":31248384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T14:43:46.138809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1490995828,"revision":34599,"compact-revision":32361} {"level":"info","ts":"2026-05-19T14:47:49.378277Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T14:47:49.38447Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-19T14:47:49.384605Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-19T14:48:46.019396Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36896} {"level":"info","ts":"2026-05-19T14:48:46.149972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36896,"took":"123.224623ms","hash":240215230,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":32243712,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T14:48:46.150036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":240215230,"revision":36896,"compact-revision":34599} {"level":"info","ts":"2026-05-19T14:53:46.026869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39192} {"level":"info","ts":"2026-05-19T14:53:46.152055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39192,"took":"120.951754ms","hash":3423502602,"current-db-size-bytes":58990592,"current-db-size":"59 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T14:53:46.152128Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3423502602,"revision":39192,"compact-revision":36896}