{"level":"warn","ts":"2026-06-03T21:49:01.181854Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T21:49:01.181941Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-06-03T21:49:01.182004Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T21:49:01.182016Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-03T21:49:01.182032Z","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-06-03T21:49:01.18262Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-06-03T21:49:01.182685Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-06-03T21:49:01.18785Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.955151ms"} {"level":"info","ts":"2026-06-03T21:49:01.196362Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-06-03T21:49:01.196548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-06-03T21:49:01.196577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-06-03T21:49:01.196647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-03T21:49:01.196723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-06-03T21:49:01.196862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-06-03T21:49:01.205169Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-03T21:49:01.207398Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-03T21:49:01.213319Z","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-06-03T21:49:01.218688Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-03T21:49:01.218879Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-03T21:49:01.219664Z","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-06-03T21:49:01.21984Z","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-06-03T21:49:01.21986Z","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-06-03T21:49:01.220991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-06-03T21:49:01.22121Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-03T21:49:01.223177Z","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-06-03T21:49:01.223255Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-03T21:49:01.223334Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-03T21:49:01.223458Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-03T21:49:01.223509Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-03T21:49:01.498132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-06-03T21:49:01.498201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-03T21:49:01.498216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-06-03T21:49:01.498229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-06-03T21:49:01.498237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-03T21:49:01.498261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-06-03T21:49:01.498274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-03T21:49:01.500496Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-06-03T21:49:01.50072Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:49:01.501641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T21:49:01.502007Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T21:49:01.502206Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-03T21:49:01.502248Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-03T21:49:01.502287Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:49:01.50236Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:49:01.5024Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:49:01.505114Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-06-03T21:49:01.506922Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-03T21:49:04.533179Z","caller":"traceutil/trace.go:171","msg":"trace[2121498092] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"102.086504ms","start":"2026-06-03T21:49:04.431064Z","end":"2026-06-03T21:49:04.53315Z","steps":["trace[2121498092] 'process raft request' (duration: 28.031899ms)","trace[2121498092] 'compare' (duration: 73.955112ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:04.737049Z","caller":"traceutil/trace.go:171","msg":"trace[1672400010] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"163.097323ms","start":"2026-06-03T21:49:04.573928Z","end":"2026-06-03T21:49:04.737025Z","steps":["trace[1672400010] 'process raft request' (duration: 75.730276ms)","trace[1672400010] 'compare' (duration: 87.250934ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:04.925204Z","caller":"traceutil/trace.go:171","msg":"trace[882938281] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"113.387488ms","start":"2026-06-03T21:49:04.811781Z","end":"2026-06-03T21:49:04.925168Z","steps":["trace[882938281] 'process raft request' (duration: 62.440663ms)","trace[882938281] 'compare' (duration: 50.814532ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:49:06.332673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.627234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3495"} {"level":"info","ts":"2026-06-03T21:49:06.332756Z","caller":"traceutil/trace.go:171","msg":"trace[1472479787] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:259; }","duration":"172.748648ms","start":"2026-06-03T21:49:06.159995Z","end":"2026-06-03T21:49:06.332743Z","steps":["trace[1472479787] 'range keys from in-memory index tree' (duration: 172.552523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:06.332673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.626568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-03T21:49:06.332865Z","caller":"traceutil/trace.go:171","msg":"trace[903408687] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:259; }","duration":"232.866314ms","start":"2026-06-03T21:49:06.099988Z","end":"2026-06-03T21:49:06.332854Z","steps":["trace[903408687] 'range keys from in-memory index tree' (duration: 232.539286ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:06.452624Z","caller":"traceutil/trace.go:171","msg":"trace[919244632] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"114.964257ms","start":"2026-06-03T21:49:06.33763Z","end":"2026-06-03T21:49:06.452594Z","steps":["trace[919244632] 'process raft request' (duration: 114.72726ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:06.457316Z","caller":"traceutil/trace.go:171","msg":"trace[617594085] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"117.713883ms","start":"2026-06-03T21:49:06.339555Z","end":"2026-06-03T21:49:06.457268Z","steps":["trace[617594085] 'process raft request' (duration: 117.519989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:06.67677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.739559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:49:06.676876Z","caller":"traceutil/trace.go:171","msg":"trace[623614768] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"184.218405ms","start":"2026-06-03T21:49:06.492632Z","end":"2026-06-03T21:49:06.676851Z","steps":["trace[623614768] 'process raft request' (duration: 60.039515ms)","trace[623614768] 'compare' (duration: 123.603205ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:06.678136Z","caller":"traceutil/trace.go:171","msg":"trace[158360699] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"184.40439ms","start":"2026-06-03T21:49:06.493708Z","end":"2026-06-03T21:49:06.678113Z","steps":["trace[158360699] 'process raft request' (duration: 184.211535ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:06.837616Z","caller":"traceutil/trace.go:171","msg":"trace[279803315] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"150.843486ms","start":"2026-06-03T21:49:06.686733Z","end":"2026-06-03T21:49:06.837576Z","steps":["trace[279803315] 'process raft request' (duration: 94.142902ms)","trace[279803315] 'compare' (duration: 56.455598ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:49:11.25783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.202616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:49:11.258008Z","caller":"traceutil/trace.go:171","msg":"trace[1302545574] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"156.734689ms","start":"2026-06-03T21:49:11.101249Z","end":"2026-06-03T21:49:11.257984Z","steps":["trace[1302545574] 'read index received' (duration: 19.031592ms)","trace[1302545574] 'applied index is now lower than readState.Index' (duration: 137.701457ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:11.258023Z","caller":"traceutil/trace.go:171","msg":"trace[102066270] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"184.174174ms","start":"2026-06-03T21:49:11.07382Z","end":"2026-06-03T21:49:11.257994Z","steps":["trace[102066270] 'process raft request' (duration: 46.486597ms)","trace[102066270] 'compare' (duration: 137.056842ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:49:11.258226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.954694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-03T21:49:11.258318Z","caller":"traceutil/trace.go:171","msg":"trace[2129107463] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:370; }","duration":"157.079337ms","start":"2026-06-03T21:49:11.101206Z","end":"2026-06-03T21:49:11.258286Z","steps":["trace[2129107463] 'agreement among raft nodes before linearized reading' (duration: 156.891903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:11.258169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.332781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-03T21:49:11.258765Z","caller":"traceutil/trace.go:171","msg":"trace[1365417115] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"107.947915ms","start":"2026-06-03T21:49:11.1508Z","end":"2026-06-03T21:49:11.258748Z","steps":["trace[1365417115] 'agreement among raft nodes before linearized reading' (duration: 107.31976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:11.527244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.625936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:49:11.527467Z","caller":"traceutil/trace.go:171","msg":"trace[1367698863] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"257.194544ms","start":"2026-06-03T21:49:11.270255Z","end":"2026-06-03T21:49:11.52745Z","steps":["trace[1367698863] 'process raft request' (duration: 257.143073ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:11.527483Z","caller":"traceutil/trace.go:171","msg":"trace[1099272191] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"263.990198ms","start":"2026-06-03T21:49:11.263458Z","end":"2026-06-03T21:49:11.527448Z","steps":["trace[1099272191] 'process raft request' (duration: 154.022423ms)","trace[1099272191] 'compare' (duration: 109.476943ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:11.52751Z","caller":"traceutil/trace.go:171","msg":"trace[1273149182] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"261.966698ms","start":"2026-06-03T21:49:11.265504Z","end":"2026-06-03T21:49:11.527471Z","steps":["trace[1273149182] 'process raft request' (duration: 261.838815ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:11.711758Z","caller":"traceutil/trace.go:171","msg":"trace[2015964035] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"177.286477ms","start":"2026-06-03T21:49:11.534448Z","end":"2026-06-03T21:49:11.711735Z","steps":["trace[2015964035] 'process raft request' (duration: 177.145333ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:11.711874Z","caller":"traceutil/trace.go:171","msg":"trace[85641213] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"177.897202ms","start":"2026-06-03T21:49:11.533919Z","end":"2026-06-03T21:49:11.711816Z","steps":["trace[85641213] 'process raft request' (duration: 93.231731ms)","trace[85641213] 'compare' (duration: 84.303203ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:11.7119Z","caller":"traceutil/trace.go:171","msg":"trace[1477918337] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"176.335184ms","start":"2026-06-03T21:49:11.535532Z","end":"2026-06-03T21:49:11.711867Z","steps":["trace[1477918337] 'process raft request' (duration: 176.109208ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:11.712071Z","caller":"traceutil/trace.go:171","msg":"trace[580520464] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"171.108427ms","start":"2026-06-03T21:49:11.540919Z","end":"2026-06-03T21:49:11.712027Z","steps":["trace[580520464] 'process raft request' (duration: 170.770379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:19.882511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955151187775646,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T21:49:19.995723Z","caller":"traceutil/trace.go:171","msg":"trace[2128558991] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"613.885005ms","start":"2026-06-03T21:49:19.381802Z","end":"2026-06-03T21:49:19.995687Z","steps":["trace[2128558991] 'read index received' (duration: 613.568128ms)","trace[2128558991] 'applied index is now lower than readState.Index' (duration: 315.047µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:19.995794Z","caller":"traceutil/trace.go:171","msg":"trace[718676589] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"659.949214ms","start":"2026-06-03T21:49:19.33581Z","end":"2026-06-03T21:49:19.995759Z","steps":["trace[718676589] 'process raft request' (duration: 659.592106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:19.995954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.134611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T21:49:19.995943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:19.335759Z","time spent":"660.106397ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:49:19.996016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.921286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-03T21:49:19.996038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.466811ms","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-06-03T21:49:19.996048Z","caller":"traceutil/trace.go:171","msg":"trace[1176412259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"614.278774ms","start":"2026-06-03T21:49:19.381749Z","end":"2026-06-03T21:49:19.996028Z","steps":["trace[1176412259] 'agreement among raft nodes before linearized reading' (duration: 614.138981ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:19.996069Z","caller":"traceutil/trace.go:171","msg":"trace[1302565537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"434.055588ms","start":"2026-06-03T21:49:19.561996Z","end":"2026-06-03T21:49:19.996051Z","steps":["trace[1302565537] 'agreement among raft nodes before linearized reading' (duration: 433.915675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:19.996107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:19.381727Z","time spent":"614.368187ms","remote":"127.0.0.1:51846","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-06-03T21:49:19.996119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:19.561975Z","time spent":"434.13358ms","remote":"127.0.0.1:52082","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":"info","ts":"2026-06-03T21:49:19.996102Z","caller":"traceutil/trace.go:171","msg":"trace[1652115199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"229.536603ms","start":"2026-06-03T21:49:19.76655Z","end":"2026-06-03T21:49:19.996087Z","steps":["trace[1652115199] 'agreement among raft nodes before linearized reading' (duration: 229.4148ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:26.036786Z","caller":"traceutil/trace.go:171","msg":"trace[1008217258] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"372.529851ms","start":"2026-06-03T21:49:25.664209Z","end":"2026-06-03T21:49:26.036739Z","steps":["trace[1008217258] 'process raft request' (duration: 365.959665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:26.037046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:25.664182Z","time spent":"372.712767ms","remote":"127.0.0.1:51866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:49:48.273126Z","caller":"traceutil/trace.go:171","msg":"trace[1692997182] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"382.816237ms","start":"2026-06-03T21:49:47.890283Z","end":"2026-06-03T21:49:48.273099Z","steps":["trace[1692997182] 'read index received' (duration: 381.832724ms)","trace[1692997182] 'applied index is now lower than readState.Index' (duration: 982.393µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:48.273672Z","caller":"traceutil/trace.go:171","msg":"trace[185322673] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"676.952169ms","start":"2026-06-03T21:49:47.596207Z","end":"2026-06-03T21:49:48.273159Z","steps":["trace[185322673] 'process raft request' (duration: 676.085678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:48.274309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:47.596129Z","time spent":"677.769858ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:49:48.274327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.021096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wq4cp\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-03T21:49:48.274435Z","caller":"traceutil/trace.go:171","msg":"trace[748441548] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wq4cp; range_end:; response_count:1; response_revision:637; }","duration":"384.185331ms","start":"2026-06-03T21:49:47.890206Z","end":"2026-06-03T21:49:48.274392Z","steps":["trace[748441548] 'agreement among raft nodes before linearized reading' (duration: 383.055413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:48.274477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:47.890185Z","time spent":"384.283212ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-wq4cp\" "} {"level":"info","ts":"2026-06-03T21:49:48.870958Z","caller":"traceutil/trace.go:171","msg":"trace[737806133] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"588.736786ms","start":"2026-06-03T21:49:48.282194Z","end":"2026-06-03T21:49:48.87093Z","steps":["trace[737806133] 'process raft request' (duration: 588.50701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:48.871124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:48.282178Z","time spent":"588.86208ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:49:48.871394Z","caller":"traceutil/trace.go:171","msg":"trace[83507435] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"589.044124ms","start":"2026-06-03T21:49:48.282335Z","end":"2026-06-03T21:49:48.87138Z","steps":["trace[83507435] 'process raft request' (duration: 588.549272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:48.871485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:48.282296Z","time spent":"589.140666ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:49:48.871642Z","caller":"traceutil/trace.go:171","msg":"trace[2083715072] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"581.877392ms","start":"2026-06-03T21:49:48.289731Z","end":"2026-06-03T21:49:48.871608Z","steps":["trace[2083715072] 'process raft request' (duration: 581.562535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:48.871813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:48.289717Z","time spent":"582.003386ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:49:48.904032Z","caller":"traceutil/trace.go:171","msg":"trace[1662083341] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"590.704093ms","start":"2026-06-03T21:49:48.313306Z","end":"2026-06-03T21:49:48.90401Z","steps":["trace[1662083341] 'process raft request' (duration: 590.479688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:48.90418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:48.313284Z","time spent":"590.803715ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:49:48.904331Z","caller":"traceutil/trace.go:171","msg":"trace[1471438183] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"323.43134ms","start":"2026-06-03T21:49:48.580884Z","end":"2026-06-03T21:49:48.904315Z","steps":["trace[1471438183] 'process raft request' (duration: 323.311808ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:48.904328Z","caller":"traceutil/trace.go:171","msg":"trace[921299752] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"569.950718ms","start":"2026-06-03T21:49:48.334329Z","end":"2026-06-03T21:49:48.904279Z","steps":["trace[921299752] 'process raft request' (duration: 569.585149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:48.904455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:48.580856Z","time spent":"323.534683ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:49:48.904506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:48.334301Z","time spent":"570.123472ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:49:49.882792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955151187776073,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T21:49:49.963593Z","caller":"traceutil/trace.go:171","msg":"trace[1597062737] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"581.841972ms","start":"2026-06-03T21:49:49.381723Z","end":"2026-06-03T21:49:49.963565Z","steps":["trace[1597062737] 'read index received' (duration: 581.040152ms)","trace[1597062737] 'applied index is now lower than readState.Index' (duration: 800.359µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:49:49.963738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.988545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:49:49.963732Z","caller":"traceutil/trace.go:171","msg":"trace[1180036636] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"662.428203ms","start":"2026-06-03T21:49:49.301259Z","end":"2026-06-03T21:49:49.963687Z","steps":["trace[1180036636] 'process raft request' (duration: 661.798138ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:49.963775Z","caller":"traceutil/trace.go:171","msg":"trace[199567402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"582.068897ms","start":"2026-06-03T21:49:49.381694Z","end":"2026-06-03T21:49:49.963763Z","steps":["trace[199567402] 'agreement among raft nodes before linearized reading' (duration: 581.981175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:49.963822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:49.381679Z","time spent":"582.136338ms","remote":"127.0.0.1:51848","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-06-03T21:49:49.963919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:49.301229Z","time spent":"662.590718ms","remote":"127.0.0.1:51920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:50:00.558142Z","caller":"traceutil/trace.go:171","msg":"trace[1938592647] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"191.247089ms","start":"2026-06-03T21:50:00.366867Z","end":"2026-06-03T21:50:00.558114Z","steps":["trace[1938592647] 'read index received' (duration: 190.984273ms)","trace[1938592647] 'applied index is now lower than readState.Index' (duration: 259.586µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:00.558239Z","caller":"traceutil/trace.go:171","msg":"trace[2074025896] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"222.841393ms","start":"2026-06-03T21:50:00.335375Z","end":"2026-06-03T21:50:00.558216Z","steps":["trace[2074025896] 'process raft request' (duration: 222.534014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:00.558488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.596338ms","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-06-03T21:50:00.558581Z","caller":"traceutil/trace.go:171","msg":"trace[1572034922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"191.70591ms","start":"2026-06-03T21:50:00.36685Z","end":"2026-06-03T21:50:00.558556Z","steps":["trace[1572034922] 'agreement among raft nodes before linearized reading' (duration: 191.424084ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:01.470361Z","caller":"traceutil/trace.go:171","msg":"trace[605673143] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"126.019624ms","start":"2026-06-03T21:50:01.344297Z","end":"2026-06-03T21:50:01.470317Z","steps":["trace[605673143] 'process raft request' (duration: 69.924217ms)","trace[605673143] 'compare' (duration: 55.879142ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:07.057735Z","caller":"traceutil/trace.go:171","msg":"trace[823460169] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"382.249234ms","start":"2026-06-03T21:50:06.675465Z","end":"2026-06-03T21:50:07.057714Z","steps":["trace[823460169] 'process raft request' (duration: 382.052739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:07.057861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:06.675438Z","time spent":"382.359537ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:50:14.470254Z","caller":"traceutil/trace.go:171","msg":"trace[431934596] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"140.285774ms","start":"2026-06-03T21:50:14.329943Z","end":"2026-06-03T21:50:14.470228Z","steps":["trace[431934596] 'process raft request' (duration: 140.072199ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:14.634058Z","caller":"traceutil/trace.go:171","msg":"trace[1733054271] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"157.404192ms","start":"2026-06-03T21:50:14.476623Z","end":"2026-06-03T21:50:14.634027Z","steps":["trace[1733054271] 'process raft request' (duration: 135.645363ms)","trace[1733054271] 'compare' (duration: 21.632296ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:14.681109Z","caller":"traceutil/trace.go:171","msg":"trace[679049026] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"203.134803ms","start":"2026-06-03T21:50:14.477957Z","end":"2026-06-03T21:50:14.681092Z","steps":["trace[679049026] 'process raft request' (duration: 203.076682ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:14.681262Z","caller":"traceutil/trace.go:171","msg":"trace[1508416274] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"203.626815ms","start":"2026-06-03T21:50:14.477596Z","end":"2026-06-03T21:50:14.681223Z","steps":["trace[1508416274] 'process raft request' (duration: 203.329598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:35.487716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.026425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:35.487796Z","caller":"traceutil/trace.go:171","msg":"trace[1661801678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"105.136468ms","start":"2026-06-03T21:50:35.382644Z","end":"2026-06-03T21:50:35.48778Z","steps":["trace[1661801678] 'range keys from in-memory index tree' (duration: 104.902363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:36.164989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.643933ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-03T21:50:36.165283Z","caller":"traceutil/trace.go:171","msg":"trace[1987502397] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"397.608681ms","start":"2026-06-03T21:50:35.767554Z","end":"2026-06-03T21:50:36.165163Z","steps":["trace[1987502397] 'read index received' (duration: 37.491µs)","trace[1987502397] 'applied index is now lower than readState.Index' (duration: 397.56868ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:50:36.16565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.077333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T21:50:36.165809Z","caller":"traceutil/trace.go:171","msg":"trace[2102044016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"398.289927ms","start":"2026-06-03T21:50:35.767499Z","end":"2026-06-03T21:50:36.165789Z","steps":["trace[2102044016] 'agreement among raft nodes before linearized reading' (duration: 397.963719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:36.165857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:35.767473Z","time spent":"398.373259ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T21:50:36.165957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.982212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-03T21:50:36.16599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:35.555881Z","time spent":"610.091981ms","remote":"127.0.0.1:51866","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-03T21:50:36.166057Z","caller":"traceutil/trace.go:171","msg":"trace[1511954059] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1211; }","duration":"384.162496ms","start":"2026-06-03T21:50:35.781875Z","end":"2026-06-03T21:50:36.166037Z","steps":["trace[1511954059] 'agreement among raft nodes before linearized reading' (duration: 383.714935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:36.166126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:35.781778Z","time spent":"384.30887ms","remote":"127.0.0.1:52182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-06-03T21:50:38.349958Z","caller":"traceutil/trace.go:171","msg":"trace[1577501972] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"137.252704ms","start":"2026-06-03T21:50:38.212683Z","end":"2026-06-03T21:50:38.349936Z","steps":["trace[1577501972] 'process raft request' (duration: 137.196122ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:38.350116Z","caller":"traceutil/trace.go:171","msg":"trace[932805886] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"170.235031ms","start":"2026-06-03T21:50:38.179828Z","end":"2026-06-03T21:50:38.350063Z","steps":["trace[932805886] 'process raft request' (duration: 159.221135ms)","trace[932805886] 'compare' (duration: 10.695508ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:39.970052Z","caller":"traceutil/trace.go:171","msg":"trace[171914648] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"171.237535ms","start":"2026-06-03T21:50:39.798772Z","end":"2026-06-03T21:50:39.970009Z","steps":["trace[171914648] 'process raft request' (duration: 146.821246ms)","trace[171914648] 'compare' (duration: 24.101051ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:41.152618Z","caller":"traceutil/trace.go:171","msg":"trace[1713249316] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"111.023281ms","start":"2026-06-03T21:50:41.041544Z","end":"2026-06-03T21:50:41.152567Z","steps":["trace[1713249316] 'process raft request' (duration: 110.037827ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:43.439019Z","caller":"traceutil/trace.go:171","msg":"trace[1374666892] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"112.346564ms","start":"2026-06-03T21:50:43.326653Z","end":"2026-06-03T21:50:43.438999Z","steps":["trace[1374666892] 'read index received' (duration: 112.271752ms)","trace[1374666892] 'applied index is now lower than readState.Index' (duration: 73.952µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:43.439127Z","caller":"traceutil/trace.go:171","msg":"trace[1253419661] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"115.654314ms","start":"2026-06-03T21:50:43.323426Z","end":"2026-06-03T21:50:43.439081Z","steps":["trace[1253419661] 'process raft request' (duration: 115.453599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:43.439215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.554718ms","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-06-03T21:50:43.439246Z","caller":"traceutil/trace.go:171","msg":"trace[1686585650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"112.62108ms","start":"2026-06-03T21:50:43.326616Z","end":"2026-06-03T21:50:43.439237Z","steps":["trace[1686585650] 'agreement among raft nodes before linearized reading' (duration: 112.485647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:43.439438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.283324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-03T21:50:43.43948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.235983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-03T21:50:43.439527Z","caller":"traceutil/trace.go:171","msg":"trace[1738928656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"103.414457ms","start":"2026-06-03T21:50:43.336097Z","end":"2026-06-03T21:50:43.439511Z","steps":["trace[1738928656] 'agreement among raft nodes before linearized reading' (duration: 103.187581ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:43.439599Z","caller":"traceutil/trace.go:171","msg":"trace[1162560433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"103.377756ms","start":"2026-06-03T21:50:43.336193Z","end":"2026-06-03T21:50:43.439571Z","steps":["trace[1162560433] 'agreement among raft nodes before linearized reading' (duration: 103.10116ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:56.118814Z","caller":"traceutil/trace.go:171","msg":"trace[219935046] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"140.207546ms","start":"2026-06-03T21:50:55.978581Z","end":"2026-06-03T21:50:56.118788Z","steps":["trace[219935046] 'process raft request' (duration: 52.964939ms)","trace[219935046] 'compare' (duration: 86.9773ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:56.138464Z","caller":"traceutil/trace.go:171","msg":"trace[1358676645] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"159.134363ms","start":"2026-06-03T21:50:55.979297Z","end":"2026-06-03T21:50:56.138431Z","steps":["trace[1358676645] 'process raft request' (duration: 158.819485ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:56.138638Z","caller":"traceutil/trace.go:171","msg":"trace[1782658567] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1491; }","duration":"132.580181ms","start":"2026-06-03T21:50:56.005912Z","end":"2026-06-03T21:50:56.138492Z","steps":["trace[1782658567] 'read index received' (duration: 25.652029ms)","trace[1782658567] 'applied index is now lower than readState.Index' (duration: 106.926562ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:56.138703Z","caller":"traceutil/trace.go:171","msg":"trace[750958355] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"137.590373ms","start":"2026-06-03T21:50:56.001058Z","end":"2026-06-03T21:50:56.138648Z","steps":["trace[750958355] 'process raft request' (duration: 137.367897ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:56.138778Z","caller":"traceutil/trace.go:171","msg":"trace[256775129] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"158.426216ms","start":"2026-06-03T21:50:55.980287Z","end":"2026-06-03T21:50:56.138713Z","steps":["trace[256775129] 'process raft request' (duration: 158.035387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:56.13899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.018271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:56.139073Z","caller":"traceutil/trace.go:171","msg":"trace[848782948] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1451; }","duration":"133.182305ms","start":"2026-06-03T21:50:56.005869Z","end":"2026-06-03T21:50:56.139052Z","steps":["trace[848782948] 'agreement among raft nodes before linearized reading' (duration: 133.011251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:56.15905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.963965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-03T21:50:56.159122Z","caller":"traceutil/trace.go:171","msg":"trace[1582027906] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1452; }","duration":"117.080147ms","start":"2026-06-03T21:50:56.042024Z","end":"2026-06-03T21:50:56.159104Z","steps":["trace[1582027906] 'agreement among raft nodes before linearized reading' (duration: 116.864922ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:56.331068Z","caller":"traceutil/trace.go:171","msg":"trace[1396810778] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"165.120568ms","start":"2026-06-03T21:50:56.165914Z","end":"2026-06-03T21:50:56.331034Z","steps":["trace[1396810778] 'process raft request' (duration: 164.884672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:58.882307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955151187777693,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T21:50:59.354995Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.093733182s","expected-duration":"1s"} {"level":"info","ts":"2026-06-03T21:50:59.355579Z","caller":"traceutil/trace.go:171","msg":"trace[97963465] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"1.094401197s","start":"2026-06-03T21:50:58.261152Z","end":"2026-06-03T21:50:59.355553Z","steps":["trace[97963465] 'process raft request' (duration: 1.094196812s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:59.355755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:58.26113Z","time spent":"1.09450371s","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:50:59.455191Z","caller":"traceutil/trace.go:171","msg":"trace[1475131368] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1517; }","duration":"1.073190365s","start":"2026-06-03T21:50:58.381982Z","end":"2026-06-03T21:50:59.455172Z","steps":["trace[1475131368] 'read index received' (duration: 973.274842ms)","trace[1475131368] 'applied index is now lower than readState.Index' (duration: 99.914803ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:59.455248Z","caller":"traceutil/trace.go:171","msg":"trace[1866328801] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"1.019010388s","start":"2026-06-03T21:50:58.436216Z","end":"2026-06-03T21:50:59.455226Z","steps":["trace[1866328801] 'process raft request' (duration: 1.014201491s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:59.455299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073311449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:59.455323Z","caller":"traceutil/trace.go:171","msg":"trace[1594440683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1475; }","duration":"1.073389961s","start":"2026-06-03T21:50:58.381926Z","end":"2026-06-03T21:50:59.455316Z","steps":["trace[1594440683] 'agreement among raft nodes before linearized reading' (duration: 1.073329129s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:59.455347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:58.381906Z","time spent":"1.073432681s","remote":"127.0.0.1:51848","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-06-03T21:50:59.455362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:58.436199Z","time spent":"1.019096769s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:50:59.459149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.662688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T21:50:59.459231Z","caller":"traceutil/trace.go:171","msg":"trace[1623850893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1477; }","duration":"996.812752ms","start":"2026-06-03T21:50:58.462401Z","end":"2026-06-03T21:50:59.459214Z","steps":["trace[1623850893] 'agreement among raft nodes before linearized reading' (duration: 996.610867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:59.45927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:58.462378Z","time spent":"996.883714ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T21:51:16.533161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.91603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:16.533264Z","caller":"traceutil/trace.go:171","msg":"trace[1075242158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1783; }","duration":"150.078053ms","start":"2026-06-03T21:51:16.383162Z","end":"2026-06-03T21:51:16.53324Z","steps":["trace[1075242158] 'range keys from in-memory index tree' (duration: 149.804667ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:17.785082Z","caller":"traceutil/trace.go:171","msg":"trace[2127051976] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"130.664606ms","start":"2026-06-03T21:51:17.654386Z","end":"2026-06-03T21:51:17.78505Z","steps":["trace[2127051976] 'process raft request' (duration: 97.173997ms)","trace[2127051976] 'compare' (duration: 33.264753ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:51:17.785486Z","caller":"traceutil/trace.go:171","msg":"trace[1935367995] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"131.013944ms","start":"2026-06-03T21:51:17.654442Z","end":"2026-06-03T21:51:17.785456Z","steps":["trace[1935367995] 'process raft request' (duration: 130.556863ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:17.785687Z","caller":"traceutil/trace.go:171","msg":"trace[1546788091] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"131.097816ms","start":"2026-06-03T21:51:17.654553Z","end":"2026-06-03T21:51:17.785651Z","steps":["trace[1546788091] 'process raft request' (duration: 130.808569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:27.896017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955151187778587,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T21:51:28.299099Z","caller":"traceutil/trace.go:171","msg":"trace[1309627672] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"917.555383ms","start":"2026-06-03T21:51:27.381487Z","end":"2026-06-03T21:51:28.299042Z","steps":["trace[1309627672] 'process raft request' (duration: 917.329908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:28.299313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.381452Z","time spent":"917.750448ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:51:29.055074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.515365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:51:29.055325Z","caller":"traceutil/trace.go:171","msg":"trace[1278947113] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"1.623922328s","start":"2026-06-03T21:51:27.431353Z","end":"2026-06-03T21:51:29.055275Z","steps":["trace[1278947113] 'process raft request' (duration: 1.354053344s)","trace[1278947113] 'compare' (duration: 269.177338ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:29.055565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.431332Z","time spent":"1.624098243s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:51:29.055731Z","caller":"traceutil/trace.go:171","msg":"trace[2131085636] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"1.413948627s","start":"2026-06-03T21:51:27.641743Z","end":"2026-06-03T21:51:29.055692Z","steps":["trace[2131085636] 'process raft request' (duration: 1.413446845s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.055947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.641717Z","time spent":"1.414115701s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:51:29.055917Z","caller":"traceutil/trace.go:171","msg":"trace[127848761] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"1.357973597s","start":"2026-06-03T21:51:27.697905Z","end":"2026-06-03T21:51:29.055879Z","steps":["trace[127848761] 'process raft request' (duration: 1.357722541s)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:29.056079Z","caller":"traceutil/trace.go:171","msg":"trace[1146118501] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"1.142820043s","start":"2026-06-03T21:51:27.913243Z","end":"2026-06-03T21:51:29.056063Z","steps":["trace[1146118501] 'process raft request' (duration: 1.142579317s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.056185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.913213Z","time spent":"1.142910555s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:51:29.056192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.697877Z","time spent":"1.358164952s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:51:29.056054Z","caller":"traceutil/trace.go:171","msg":"trace[1810681861] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1980; }","duration":"1.660438846s","start":"2026-06-03T21:51:27.395577Z","end":"2026-06-03T21:51:29.056015Z","steps":["trace[1810681861] 'read index received' (duration: 903.396152ms)","trace[1810681861] 'applied index is now lower than readState.Index' (duration: 757.041204ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:29.056315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.660729803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3de960d5-88d2-4619-b361-9b590bbbc910\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-03T21:51:29.056637Z","caller":"traceutil/trace.go:171","msg":"trace[784750021] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3de960d5-88d2-4619-b361-9b590bbbc910; range_end:; response_count:1; response_revision:1913; }","duration":"1.661066192s","start":"2026-06-03T21:51:27.395538Z","end":"2026-06-03T21:51:29.056604Z","steps":["trace[784750021] 'agreement among raft nodes before linearized reading' (duration: 1.66057297s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.056751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.395509Z","time spent":"1.661207716s","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3de960d5-88d2-4619-b361-9b590bbbc910\" "} {"level":"warn","ts":"2026-06-03T21:51:29.057492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202998073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-03T21:51:29.057547Z","caller":"traceutil/trace.go:171","msg":"trace[950707564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1913; }","duration":"1.203080926s","start":"2026-06-03T21:51:27.854453Z","end":"2026-06-03T21:51:29.057534Z","steps":["trace[950707564] 'agreement among raft nodes before linearized reading' (duration: 1.202870561s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.057582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.854443Z","time spent":"1.203132636s","remote":"127.0.0.1:52082","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-06-03T21:51:29.05759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207308857s","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-06-03T21:51:29.057487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346446171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3de960d5-88d2-4619-b361-9b590bbbc910\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-06-03T21:51:29.057651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.922266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T21:51:29.057692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217779239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-03T21:51:29.057715Z","caller":"traceutil/trace.go:171","msg":"trace[1706461970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1913; }","duration":"1.217834221s","start":"2026-06-03T21:51:27.839874Z","end":"2026-06-03T21:51:29.057708Z","steps":["trace[1706461970] 'agreement among raft nodes before linearized reading' (duration: 1.217760619s)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:29.057718Z","caller":"traceutil/trace.go:171","msg":"trace[1341684674] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1913; }","duration":"897.017008ms","start":"2026-06-03T21:51:28.160683Z","end":"2026-06-03T21:51:29.0577Z","steps":["trace[1341684674] 'agreement among raft nodes before linearized reading' (duration: 896.896396ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:29.05769Z","caller":"traceutil/trace.go:171","msg":"trace[736896513] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3de960d5-88d2-4619-b361-9b590bbbc910; range_end:; response_count:1; response_revision:1913; }","duration":"1.346664615s","start":"2026-06-03T21:51:27.710992Z","end":"2026-06-03T21:51:29.057656Z","steps":["trace[736896513] 'agreement among raft nodes before linearized reading' (duration: 1.346321467s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.057741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.83985Z","time spent":"1.217885882s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-03T21:51:29.057757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330695621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-03T21:51:29.057767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.160663Z","time spent":"897.09353ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-06-03T21:51:29.05762Z","caller":"traceutil/trace.go:171","msg":"trace[79743110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1913; }","duration":"1.207352868s","start":"2026-06-03T21:51:27.850255Z","end":"2026-06-03T21:51:29.057608Z","steps":["trace[79743110] 'agreement among raft nodes before linearized reading' (duration: 1.207271576s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.057792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33567241s","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-06-03T21:51:29.057807Z","caller":"traceutil/trace.go:171","msg":"trace[545458192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1913; }","duration":"1.330773913s","start":"2026-06-03T21:51:27.72702Z","end":"2026-06-03T21:51:29.057794Z","steps":["trace[545458192] 'agreement among raft nodes before linearized reading' (duration: 1.330624089s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.0578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.710979Z","time spent":"1.346787189s","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3de960d5-88d2-4619-b361-9b590bbbc910\" "} {"level":"warn","ts":"2026-06-03T21:51:29.057843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.850239Z","time spent":"1.207591804s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-03T21:51:29.057852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.726998Z","time spent":"1.330845994s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-03T21:51:29.057856Z","caller":"traceutil/trace.go:171","msg":"trace[1496132977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1913; }","duration":"1.335759003s","start":"2026-06-03T21:51:27.722075Z","end":"2026-06-03T21:51:29.057834Z","steps":["trace[1496132977] 'agreement among raft nodes before linearized reading' (duration: 1.335594959s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.057898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.722065Z","time spent":"1.335825354s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T21:51:29.057585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.970603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:29.057949Z","caller":"traceutil/trace.go:171","msg":"trace[694995578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1913; }","duration":"674.384823ms","start":"2026-06-03T21:51:28.383551Z","end":"2026-06-03T21:51:29.057936Z","steps":["trace[694995578] 'agreement among raft nodes before linearized reading' (duration: 673.980343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.057983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.383521Z","time spent":"674.455225ms","remote":"127.0.0.1:51848","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-06-03T21:51:29.058064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.002702ms","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-06-03T21:51:29.058407Z","caller":"traceutil/trace.go:171","msg":"trace[1322063884] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1913; }","duration":"771.340699ms","start":"2026-06-03T21:51:28.286897Z","end":"2026-06-03T21:51:29.058238Z","steps":["trace[1322063884] 'agreement among raft nodes before linearized reading' (duration: 770.473509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.058504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.286868Z","time spent":"771.619636ms","remote":"127.0.0.1:35580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-03T21:51:30.508237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.857058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:51:30.508376Z","caller":"traceutil/trace.go:171","msg":"trace[1270515239] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"1.44726114s","start":"2026-06-03T21:51:29.061093Z","end":"2026-06-03T21:51:30.508354Z","steps":["trace[1270515239] 'process raft request' (duration: 993.134246ms)","trace[1270515239] 'compare' (duration: 453.711575ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:30.508443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:29.061074Z","time spent":"1.447341122s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:51:30.535497Z","caller":"traceutil/trace.go:171","msg":"trace[908465599] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"1.472468087s","start":"2026-06-03T21:51:29.062996Z","end":"2026-06-03T21:51:30.535464Z","steps":["trace[908465599] 'process raft request' (duration: 1.472394575s)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:30.535561Z","caller":"traceutil/trace.go:171","msg":"trace[1393098713] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"1.473989694s","start":"2026-06-03T21:51:29.061507Z","end":"2026-06-03T21:51:30.535497Z","steps":["trace[1393098713] 'process raft request' (duration: 1.473670816s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:30.535673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:29.062967Z","time spent":"1.47261458s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:51:30.535567Z","caller":"traceutil/trace.go:171","msg":"trace[1382500307] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"1.473580514s","start":"2026-06-03T21:51:29.061954Z","end":"2026-06-03T21:51:30.535534Z","steps":["trace[1382500307] 'process raft request' (duration: 1.473390619s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:30.53584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:29.061496Z","time spent":"1.47420255s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:51:30.536005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:29.061945Z","time spent":"1.473924024s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:51:30.536054Z","caller":"traceutil/trace.go:171","msg":"trace[1058697010] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"1.472851807s","start":"2026-06-03T21:51:29.063148Z","end":"2026-06-03T21:51:30.536Z","steps":["trace[1058697010] 'process raft request' (duration: 1.472275282s)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:30.536081Z","caller":"traceutil/trace.go:171","msg":"trace[1248966488] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"1.472617551s","start":"2026-06-03T21:51:29.063445Z","end":"2026-06-03T21:51:30.536063Z","steps":["trace[1248966488] 'process raft request' (duration: 1.472352935s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:30.536214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:29.063138Z","time spent":"1.47298327s","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:51:30.536234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:29.063434Z","time spent":"1.472695053s","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:51:30.536076Z","caller":"traceutil/trace.go:171","msg":"trace[516231400] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1985; }","duration":"863.309077ms","start":"2026-06-03T21:51:29.672743Z","end":"2026-06-03T21:51:30.536052Z","steps":["trace[516231400] 'read index received' (duration: 381.577797ms)","trace[516231400] 'applied index is now lower than readState.Index' (duration: 481.72972ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:30.536443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.683505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:30.536514Z","caller":"traceutil/trace.go:171","msg":"trace[503713236] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1919; }","duration":"863.802478ms","start":"2026-06-03T21:51:29.672693Z","end":"2026-06-03T21:51:30.536496Z","steps":["trace[503713236] 'agreement among raft nodes before linearized reading' (duration: 863.668225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:30.536569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:29.672665Z","time spent":"863.888721ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-03T21:51:30.536565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.122044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:30.536631Z","caller":"traceutil/trace.go:171","msg":"trace[250100240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1919; }","duration":"154.248647ms","start":"2026-06-03T21:51:30.382368Z","end":"2026-06-03T21:51:30.536616Z","steps":["trace[250100240] 'agreement among raft nodes before linearized reading' (duration: 154.137275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:30.536657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.461652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T21:51:30.53672Z","caller":"traceutil/trace.go:171","msg":"trace[1557548976] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:1919; }","duration":"415.555744ms","start":"2026-06-03T21:51:30.121141Z","end":"2026-06-03T21:51:30.536696Z","steps":["trace[1557548976] 'agreement among raft nodes before linearized reading' (duration: 415.468802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:30.536774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:30.121115Z","time spent":"415.643837ms","remote":"127.0.0.1:49796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-06-03T21:51:30.536568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.634261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3de960d5-88d2-4619-b361-9b590bbbc910\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-06-03T21:51:30.536832Z","caller":"traceutil/trace.go:171","msg":"trace[1209597863] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3de960d5-88d2-4619-b361-9b590bbbc910; range_end:; response_count:1; response_revision:1919; }","duration":"472.936328ms","start":"2026-06-03T21:51:30.06388Z","end":"2026-06-03T21:51:30.536817Z","steps":["trace[1209597863] 'agreement among raft nodes before linearized reading' (duration: 472.5987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:30.536823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.850971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"warn","ts":"2026-06-03T21:51:30.536864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:30.063856Z","time spent":"472.995779ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3de960d5-88d2-4619-b361-9b590bbbc910\" "} {"level":"info","ts":"2026-06-03T21:51:30.536886Z","caller":"traceutil/trace.go:171","msg":"trace[2034461108] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1919; }","duration":"122.956983ms","start":"2026-06-03T21:51:30.413911Z","end":"2026-06-03T21:51:30.536868Z","steps":["trace[2034461108] 'agreement among raft nodes before linearized reading' (duration: 122.734158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:30.536932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.92928ms","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-06-03T21:51:30.536988Z","caller":"traceutil/trace.go:171","msg":"trace[495888645] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1919; }","duration":"470.996681ms","start":"2026-06-03T21:51:30.065969Z","end":"2026-06-03T21:51:30.536966Z","steps":["trace[495888645] 'agreement among raft nodes before linearized reading' (duration: 470.808566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:30.537021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:30.065959Z","time spent":"471.055103ms","remote":"127.0.0.1:35580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-03T21:52:21.37612Z","caller":"traceutil/trace.go:171","msg":"trace[1074946707] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2321; }","duration":"394.852376ms","start":"2026-06-03T21:52:20.981241Z","end":"2026-06-03T21:52:21.376094Z","steps":["trace[1074946707] 'read index received' (duration: 394.57415ms)","trace[1074946707] 'applied index is now lower than readState.Index' (duration: 277.086µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:52:21.376246Z","caller":"traceutil/trace.go:171","msg":"trace[1756107902] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"397.4953ms","start":"2026-06-03T21:52:20.978707Z","end":"2026-06-03T21:52:21.376202Z","steps":["trace[1756107902] 'process raft request' (duration: 397.117191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:52:21.376389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.130503ms","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-06-03T21:52:21.376437Z","caller":"traceutil/trace.go:171","msg":"trace[1564180451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2237; }","duration":"395.212585ms","start":"2026-06-03T21:52:20.981211Z","end":"2026-06-03T21:52:21.376424Z","steps":["trace[1564180451] 'agreement among raft nodes before linearized reading' (duration: 395.066822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:52:21.376459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:20.978673Z","time spent":"397.664164ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:52:21.376472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:20.981199Z","time spent":"395.265386ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-03T21:52:21.416221Z","caller":"traceutil/trace.go:171","msg":"trace[1482224551] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"170.398647ms","start":"2026-06-03T21:52:21.245792Z","end":"2026-06-03T21:52:21.416191Z","steps":["trace[1482224551] 'process raft request' (duration: 170.187012ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:52:21.416227Z","caller":"traceutil/trace.go:171","msg":"trace[1187211984] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"170.284175ms","start":"2026-06-03T21:52:21.245925Z","end":"2026-06-03T21:52:21.416209Z","steps":["trace[1187211984] 'process raft request' (duration: 170.173822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:52:21.416562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.068017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T21:52:21.416592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.406099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-03T21:52:21.416632Z","caller":"traceutil/trace.go:171","msg":"trace[938711113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2241; }","duration":"434.471452ms","start":"2026-06-03T21:52:20.982149Z","end":"2026-06-03T21:52:21.41662Z","steps":["trace[938711113] 'agreement among raft nodes before linearized reading' (duration: 434.312508ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:52:21.41663Z","caller":"traceutil/trace.go:171","msg":"trace[1569561547] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2241; }","duration":"399.17198ms","start":"2026-06-03T21:52:21.017439Z","end":"2026-06-03T21:52:21.416611Z","steps":["trace[1569561547] 'agreement among raft nodes before linearized reading' (duration: 399.040547ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:52:21.416664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.054118ms","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-06-03T21:52:21.416682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:21.017423Z","time spent":"399.241232ms","remote":"127.0.0.1:47084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-06-03T21:52:21.416717Z","caller":"traceutil/trace.go:171","msg":"trace[534325703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2241; }","duration":"431.11861ms","start":"2026-06-03T21:52:20.985579Z","end":"2026-06-03T21:52:21.416698Z","steps":["trace[534325703] 'agreement among raft nodes before linearized reading' (duration: 431.010358ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:52:21.41672Z","caller":"traceutil/trace.go:171","msg":"trace[331741407] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"169.30862ms","start":"2026-06-03T21:52:21.247395Z","end":"2026-06-03T21:52:21.416704Z","steps":["trace[331741407] 'process raft request' (duration: 169.006503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:52:21.41675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:20.985571Z","time spent":"431.169281ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-03T21:52:21.416812Z","caller":"traceutil/trace.go:171","msg":"trace[1815557933] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"169.444754ms","start":"2026-06-03T21:52:21.247359Z","end":"2026-06-03T21:52:21.416804Z","steps":["trace[1815557933] 'process raft request' (duration: 168.783218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:52:21.4169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:20.982138Z","time spent":"434.752558ms","remote":"127.0.0.1:52082","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-06-03T21:52:21.416983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.985433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-03T21:52:21.417029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.697519ms","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-06-03T21:52:21.41711Z","caller":"traceutil/trace.go:171","msg":"trace[262420277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2241; }","duration":"430.186278ms","start":"2026-06-03T21:52:20.986894Z","end":"2026-06-03T21:52:21.41708Z","steps":["trace[262420277] 'agreement among raft nodes before linearized reading' (duration: 429.650535ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:52:21.417142Z","caller":"traceutil/trace.go:171","msg":"trace[539651075] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2241; }","duration":"408.844634ms","start":"2026-06-03T21:52:21.008259Z","end":"2026-06-03T21:52:21.417103Z","steps":["trace[539651075] 'agreement among raft nodes before linearized reading' (duration: 408.367962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:52:21.417174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:20.986878Z","time spent":"430.28128ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T21:52:21.417193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:21.008244Z","time spent":"408.939646ms","remote":"127.0.0.1:35580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-03T21:52:21.981943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.397401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:52:21.982126Z","caller":"traceutil/trace.go:171","msg":"trace[1192601512] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"598.199776ms","start":"2026-06-03T21:52:21.383882Z","end":"2026-06-03T21:52:21.982082Z","steps":["trace[1192601512] 'process raft request' (duration: 299.531449ms)","trace[1192601512] 'compare' (duration: 298.165375ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:52:21.98223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:21.383845Z","time spent":"598.34242ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:52:22.252749Z","caller":"traceutil/trace.go:171","msg":"trace[1072905326] linearizableReadLoop","detail":"{readStateIndex:2331; appliedIndex:2327; }","duration":"292.139691ms","start":"2026-06-03T21:52:21.960588Z","end":"2026-06-03T21:52:22.252728Z","steps":["trace[1072905326] 'read index received' (duration: 196.058895ms)","trace[1072905326] 'applied index is now lower than readState.Index' (duration: 96.079866ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:52:22.25276Z","caller":"traceutil/trace.go:171","msg":"trace[375972329] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"831.166893ms","start":"2026-06-03T21:52:21.421548Z","end":"2026-06-03T21:52:22.252715Z","steps":["trace[375972329] 'process raft request' (duration: 831.028279ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:52:22.252864Z","caller":"traceutil/trace.go:171","msg":"trace[926455095] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"830.408744ms","start":"2026-06-03T21:52:21.422448Z","end":"2026-06-03T21:52:22.252857Z","steps":["trace[926455095] 'process raft request' (duration: 830.210409ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:52:22.25274Z","caller":"traceutil/trace.go:171","msg":"trace[1441817882] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"831.379867ms","start":"2026-06-03T21:52:21.421307Z","end":"2026-06-03T21:52:22.252687Z","steps":["trace[1441817882] 'process raft request' (duration: 735.412424ms)","trace[1441817882] 'compare' (duration: 95.618985ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:52:22.252964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:21.422434Z","time spent":"830.450466ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:52:22.253125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:21.421281Z","time spent":"831.697375ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:52:22.253201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.589311ms","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:7"} {"level":"info","ts":"2026-06-03T21:52:22.25322Z","caller":"traceutil/trace.go:171","msg":"trace[1394482731] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"511.925739ms","start":"2026-06-03T21:52:21.741256Z","end":"2026-06-03T21:52:22.253182Z","steps":["trace[1394482731] 'process raft request' (duration: 511.438797ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:52:22.253287Z","caller":"traceutil/trace.go:171","msg":"trace[1181920307] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2246; }","duration":"292.718385ms","start":"2026-06-03T21:52:21.960549Z","end":"2026-06-03T21:52:22.253267Z","steps":["trace[1181920307] 'agreement among raft nodes before linearized reading' (duration: 292.557871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:52:22.253386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:21.741231Z","time spent":"512.069982ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:52:22.253144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:52:21.421527Z","time spent":"831.321556ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:52:41.697817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.157558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"info","ts":"2026-06-03T21:52:41.697912Z","caller":"traceutil/trace.go:171","msg":"trace[1369836467] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2359; }","duration":"107.296471ms","start":"2026-06-03T21:52:41.590601Z","end":"2026-06-03T21:52:41.697897Z","steps":["trace[1369836467] 'range keys from in-memory index tree' (duration: 106.983523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:53:44.116261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.265119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-03T21:53:44.116348Z","caller":"traceutil/trace.go:171","msg":"trace[1854630079] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2877; }","duration":"398.398852ms","start":"2026-06-03T21:53:43.717931Z","end":"2026-06-03T21:53:44.11633Z","steps":["trace[1854630079] 'range keys from in-memory index tree' (duration: 398.068464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:53:44.11639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:53:43.717911Z","time spent":"398.469693ms","remote":"127.0.0.1:52020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-06-03T21:53:44.116931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.551802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:53:44.11713Z","caller":"traceutil/trace.go:171","msg":"trace[1570294988] transaction","detail":"{read_only:false; response_revision:2879; number_of_response:1; }","duration":"330.457965ms","start":"2026-06-03T21:53:43.786659Z","end":"2026-06-03T21:53:44.117117Z","steps":["trace[1570294988] 'process raft request' (duration: 330.376213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:53:44.11717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:53:43.786634Z","time spent":"330.514906ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:53:44.11719Z","caller":"traceutil/trace.go:171","msg":"trace[1330403216] transaction","detail":"{read_only:false; response_revision:2878; number_of_response:1; }","duration":"340.819625ms","start":"2026-06-03T21:53:43.776299Z","end":"2026-06-03T21:53:44.117119Z","steps":["trace[1330403216] 'process raft request' (duration: 54.987686ms)","trace[1330403216] 'compare' (duration: 285.296596ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:53:44.11749Z","caller":"traceutil/trace.go:171","msg":"trace[185703720] linearizableReadLoop","detail":"{readStateIndex:2991; appliedIndex:2987; }","duration":"315.003633ms","start":"2026-06-03T21:53:43.802455Z","end":"2026-06-03T21:53:44.117458Z","steps":["trace[185703720] 'read index received' (duration: 28.846986ms)","trace[185703720] 'applied index is now lower than readState.Index' (duration: 286.154367ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:53:44.117516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:53:43.776272Z","time spent":"341.04284ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:53:44.11758Z","caller":"traceutil/trace.go:171","msg":"trace[1833029927] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"328.666361ms","start":"2026-06-03T21:53:43.788892Z","end":"2026-06-03T21:53:44.117559Z","steps":["trace[1833029927] 'process raft request' (duration: 328.387955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:53:44.11764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:53:43.788867Z","time spent":"328.744283ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:53:44.117634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.170907ms","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-06-03T21:53:44.117724Z","caller":"traceutil/trace.go:171","msg":"trace[523512965] transaction","detail":"{read_only:false; response_revision:2880; number_of_response:1; }","duration":"328.952678ms","start":"2026-06-03T21:53:43.788765Z","end":"2026-06-03T21:53:44.117717Z","steps":["trace[523512965] 'process raft request' (duration: 328.329843ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:53:44.117747Z","caller":"traceutil/trace.go:171","msg":"trace[67173271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2881; }","duration":"315.318401ms","start":"2026-06-03T21:53:43.802421Z","end":"2026-06-03T21:53:44.117739Z","steps":["trace[67173271] 'agreement among raft nodes before linearized reading' (duration: 315.125226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:53:44.117756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:53:43.788745Z","time spent":"328.9976ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:53:44.117772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:53:43.802409Z","time spent":"315.356321ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-03T21:53:44.49417Z","caller":"traceutil/trace.go:171","msg":"trace[1067432933] transaction","detail":"{read_only:false; response_revision:2882; number_of_response:1; }","duration":"596.097186ms","start":"2026-06-03T21:53:43.898032Z","end":"2026-06-03T21:53:44.494129Z","steps":["trace[1067432933] 'process raft request' (duration: 592.1056ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:53:44.494172Z","caller":"traceutil/trace.go:171","msg":"trace[1559975946] transaction","detail":"{read_only:false; response_revision:2883; number_of_response:1; }","duration":"371.769949ms","start":"2026-06-03T21:53:44.122375Z","end":"2026-06-03T21:53:44.494145Z","steps":["trace[1559975946] 'process raft request' (duration: 371.663277ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:53:44.494189Z","caller":"traceutil/trace.go:171","msg":"trace[2141170650] linearizableReadLoop","detail":"{readStateIndex:2992; appliedIndex:2991; }","duration":"374.27442ms","start":"2026-06-03T21:53:44.119879Z","end":"2026-06-03T21:53:44.494154Z","steps":["trace[2141170650] 'read index received' (duration: 370.271224ms)","trace[2141170650] 'applied index is now lower than readState.Index' (duration: 3.999636ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:53:44.49433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:53:43.898005Z","time spent":"596.24341ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:53:44.494376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:53:44.122365Z","time spent":"371.901142ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:53:44.494472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.575408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-06-03T21:53:44.494519Z","caller":"traceutil/trace.go:171","msg":"trace[1295994475] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2883; }","duration":"374.67322ms","start":"2026-06-03T21:53:44.119834Z","end":"2026-06-03T21:53:44.494507Z","steps":["trace[1295994475] 'agreement among raft nodes before linearized reading' (duration: 374.500056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:53:44.494562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.021654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T21:53:44.494591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:53:44.119816Z","time spent":"374.765802ms","remote":"127.0.0.1:52020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1468,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"info","ts":"2026-06-03T21:53:44.494613Z","caller":"traceutil/trace.go:171","msg":"trace[899247139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2883; }","duration":"113.123106ms","start":"2026-06-03T21:53:44.381478Z","end":"2026-06-03T21:53:44.494601Z","steps":["trace[899247139] 'agreement among raft nodes before linearized reading' (duration: 113.047355ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:28.32155Z","caller":"traceutil/trace.go:171","msg":"trace[395467024] linearizableReadLoop","detail":"{readStateIndex:4937; appliedIndex:4936; }","duration":"122.587512ms","start":"2026-06-03T21:57:28.198937Z","end":"2026-06-03T21:57:28.321524Z","steps":["trace[395467024] 'read index received' (duration: 122.460918ms)","trace[395467024] 'applied index is now lower than readState.Index' (duration: 125.413µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:57:28.321663Z","caller":"traceutil/trace.go:171","msg":"trace[1378053787] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"125.143844ms","start":"2026-06-03T21:57:28.196487Z","end":"2026-06-03T21:57:28.321631Z","steps":["trace[1378053787] 'process raft request' (duration: 124.851897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:28.321935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.974992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:61950"} {"level":"info","ts":"2026-06-03T21:57:28.322028Z","caller":"traceutil/trace.go:171","msg":"trace[965992688] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4756; }","duration":"123.113085ms","start":"2026-06-03T21:57:28.198895Z","end":"2026-06-03T21:57:28.322008Z","steps":["trace[965992688] 'agreement among raft nodes before linearized reading' (duration: 122.769186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:28.346575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.75286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:11981"} {"level":"info","ts":"2026-06-03T21:57:28.346676Z","caller":"traceutil/trace.go:171","msg":"trace[693823944] range","detail":"{range_begin:/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4757; }","duration":"142.862103ms","start":"2026-06-03T21:57:28.203787Z","end":"2026-06-03T21:57:28.346649Z","steps":["trace[693823944] 'agreement among raft nodes before linearized reading' (duration: 142.706059ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:28.346788Z","caller":"traceutil/trace.go:171","msg":"trace[2076676000] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"143.034496ms","start":"2026-06-03T21:57:28.203732Z","end":"2026-06-03T21:57:28.346767Z","steps":["trace[2076676000] 'process raft request' (duration: 142.550875ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:32.474075Z","caller":"traceutil/trace.go:171","msg":"trace[609979738] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"122.988792ms","start":"2026-06-03T21:57:32.351056Z","end":"2026-06-03T21:57:32.474045Z","steps":["trace[609979738] 'process raft request' (duration: 43.763397ms)","trace[609979738] 'compare' (duration: 79.071191ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:57:32.474139Z","caller":"traceutil/trace.go:171","msg":"trace[480910339] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4868; }","duration":"121.552058ms","start":"2026-06-03T21:57:32.352562Z","end":"2026-06-03T21:57:32.474114Z","steps":["trace[480910339] 'process raft request' (duration: 121.504607ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:32.474217Z","caller":"traceutil/trace.go:171","msg":"trace[1049356067] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5051; }","duration":"121.741723ms","start":"2026-06-03T21:57:32.352467Z","end":"2026-06-03T21:57:32.474208Z","steps":["trace[1049356067] 'read index received' (duration: 42.447116ms)","trace[1049356067] 'applied index is now lower than readState.Index' (duration: 79.292957ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:57:32.474372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.888676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-06-03T21:57:32.474403Z","caller":"traceutil/trace.go:171","msg":"trace[575365157] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4868; }","duration":"121.971108ms","start":"2026-06-03T21:57:32.352423Z","end":"2026-06-03T21:57:32.474394Z","steps":["trace[575365157] 'agreement among raft nodes before linearized reading' (duration: 121.813705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:37.767322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.532446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:57:37.767442Z","caller":"traceutil/trace.go:171","msg":"trace[456809235] linearizableReadLoop","detail":"{readStateIndex:5244; appliedIndex:5243; }","duration":"197.236806ms","start":"2026-06-03T21:57:37.570189Z","end":"2026-06-03T21:57:37.767426Z","steps":["trace[456809235] 'read index received' (duration: 75.492475ms)","trace[456809235] 'applied index is now lower than readState.Index' (duration: 121.743091ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:57:37.76755Z","caller":"traceutil/trace.go:171","msg":"trace[108206496] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"335.399734ms","start":"2026-06-03T21:57:37.432115Z","end":"2026-06-03T21:57:37.767514Z","steps":["trace[108206496] 'process raft request' (duration: 213.619312ms)","trace[108206496] 'compare' (duration: 121.367523ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:57:37.767675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:37.432087Z","time spent":"335.526218ms","remote":"127.0.0.1:33816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2500,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:57:37.767687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.498872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12013e77-eb9b-4e10-babd-e36e1c780bd6\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-06-03T21:57:37.767724Z","caller":"traceutil/trace.go:171","msg":"trace[1049046286] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-12013e77-eb9b-4e10-babd-e36e1c780bd6; range_end:; response_count:1; response_revision:5054; }","duration":"197.577054ms","start":"2026-06-03T21:57:37.570137Z","end":"2026-06-03T21:57:37.767714Z","steps":["trace[1049046286] 'agreement among raft nodes before linearized reading' (duration: 197.38024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:37.767926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.274876ms","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-06-03T21:57:37.768026Z","caller":"traceutil/trace.go:171","msg":"trace[414571946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5054; }","duration":"168.43688ms","start":"2026-06-03T21:57:37.599569Z","end":"2026-06-03T21:57:37.768006Z","steps":["trace[414571946] 'agreement among raft nodes before linearized reading' (duration: 168.162934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:37.768123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.73695ms","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-06-03T21:57:37.768256Z","caller":"traceutil/trace.go:171","msg":"trace[1344813429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5054; }","duration":"147.906995ms","start":"2026-06-03T21:57:37.620329Z","end":"2026-06-03T21:57:37.768236Z","steps":["trace[1344813429] 'agreement among raft nodes before linearized reading' (duration: 147.601006ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:37.885719Z","caller":"traceutil/trace.go:171","msg":"trace[123946060] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"112.394696ms","start":"2026-06-03T21:57:37.773289Z","end":"2026-06-03T21:57:37.885684Z","steps":["trace[123946060] 'process raft request' (duration: 99.588337ms)","trace[123946060] 'compare' (duration: 12.479501ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:57:49.292184Z","caller":"traceutil/trace.go:171","msg":"trace[738493015] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"115.415109ms","start":"2026-06-03T21:57:49.176705Z","end":"2026-06-03T21:57:49.29212Z","steps":["trace[738493015] 'process raft request' (duration: 115.121162ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:49.46089Z","caller":"traceutil/trace.go:171","msg":"trace[1365059574] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"120.172403ms","start":"2026-06-03T21:57:49.340688Z","end":"2026-06-03T21:57:49.460861Z","steps":["trace[1365059574] 'process raft request' (duration: 108.641364ms)","trace[1365059574] 'compare' (duration: 11.399926ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:57:49.473272Z","caller":"traceutil/trace.go:171","msg":"trace[421593133] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"111.779791ms","start":"2026-06-03T21:57:49.361474Z","end":"2026-06-03T21:57:49.473254Z","steps":["trace[421593133] 'process raft request' (duration: 111.574656ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:49.473279Z","caller":"traceutil/trace.go:171","msg":"trace[1930550598] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"111.74107ms","start":"2026-06-03T21:57:49.361498Z","end":"2026-06-03T21:57:49.473239Z","steps":["trace[1930550598] 'process raft request' (duration: 111.637858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:50.190715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.874531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-03T21:57:50.190812Z","caller":"traceutil/trace.go:171","msg":"trace[808453619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5295; }","duration":"222.033156ms","start":"2026-06-03T21:57:49.968758Z","end":"2026-06-03T21:57:50.190791Z","steps":["trace[808453619] 'range keys from in-memory index tree' (duration: 221.654736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:50.190912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.769284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-03T21:57:50.190994Z","caller":"traceutil/trace.go:171","msg":"trace[30071014] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5295; }","duration":"215.885857ms","start":"2026-06-03T21:57:49.975091Z","end":"2026-06-03T21:57:50.190977Z","steps":["trace[30071014] 'range keys from in-memory index tree' (duration: 215.608241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:50.191017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.814843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-q4474.18b5b1028d23a11a\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-06-03T21:57:50.191075Z","caller":"traceutil/trace.go:171","msg":"trace[555145561] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-q4474.18b5b1028d23a11a; range_end:; response_count:1; response_revision:5295; }","duration":"421.912656ms","start":"2026-06-03T21:57:49.769148Z","end":"2026-06-03T21:57:50.191061Z","steps":["trace[555145561] 'range keys from in-memory index tree' (duration: 421.497185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:50.191115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:49.769098Z","time spent":"422.007728ms","remote":"127.0.0.1:51908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":929,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-q4474.18b5b1028d23a11a\" "} {"level":"warn","ts":"2026-06-03T21:57:50.191144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.702591ms","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-06-03T21:57:50.191241Z","caller":"traceutil/trace.go:171","msg":"trace[1987102225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5295; }","duration":"258.829895ms","start":"2026-06-03T21:57:49.932381Z","end":"2026-06-03T21:57:50.191211Z","steps":["trace[1987102225] 'range keys from in-memory index tree' (duration: 258.508097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:50.191285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.59289ms","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-06-03T21:57:50.191343Z","caller":"traceutil/trace.go:171","msg":"trace[393812110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5295; }","duration":"215.671281ms","start":"2026-06-03T21:57:49.975656Z","end":"2026-06-03T21:57:50.191327Z","steps":["trace[393812110] 'range keys from in-memory index tree' (duration: 215.441846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:50.191512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.294702ms","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-06-03T21:57:50.191629Z","caller":"traceutil/trace.go:171","msg":"trace[185695988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5295; }","duration":"277.452135ms","start":"2026-06-03T21:57:49.914167Z","end":"2026-06-03T21:57:50.191619Z","steps":["trace[185695988] 'range keys from in-memory index tree' (duration: 277.091256ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:50.330624Z","caller":"traceutil/trace.go:171","msg":"trace[1061346542] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"135.775491ms","start":"2026-06-03T21:57:50.194821Z","end":"2026-06-03T21:57:50.330596Z","steps":["trace[1061346542] 'process raft request' (duration: 135.615568ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:50.41231Z","caller":"traceutil/trace.go:171","msg":"trace[1856261214] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"217.26682ms","start":"2026-06-03T21:57:50.195014Z","end":"2026-06-03T21:57:50.412281Z","steps":["trace[1856261214] 'process raft request' (duration: 217.034845ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:50.412316Z","caller":"traceutil/trace.go:171","msg":"trace[1146193810] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"216.723218ms","start":"2026-06-03T21:57:50.19557Z","end":"2026-06-03T21:57:50.412293Z","steps":["trace[1146193810] 'process raft request' (duration: 216.642276ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:50.412636Z","caller":"traceutil/trace.go:171","msg":"trace[973377580] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"216.773379ms","start":"2026-06-03T21:57:50.19585Z","end":"2026-06-03T21:57:50.412623Z","steps":["trace[973377580] 'process raft request' (duration: 216.40561ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:50.412785Z","caller":"traceutil/trace.go:171","msg":"trace[1182748175] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"216.890191ms","start":"2026-06-03T21:57:50.195874Z","end":"2026-06-03T21:57:50.412764Z","steps":["trace[1182748175] 'process raft request' (duration: 216.643435ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:50.412909Z","caller":"traceutil/trace.go:171","msg":"trace[349936085] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"216.510493ms","start":"2026-06-03T21:57:50.196364Z","end":"2026-06-03T21:57:50.412874Z","steps":["trace[349936085] 'process raft request' (duration: 216.308268ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:52.525739Z","caller":"traceutil/trace.go:171","msg":"trace[1709898566] linearizableReadLoop","detail":"{readStateIndex:5530; appliedIndex:5529; }","duration":"143.770194ms","start":"2026-06-03T21:57:52.381949Z","end":"2026-06-03T21:57:52.525719Z","steps":["trace[1709898566] 'read index received' (duration: 143.641461ms)","trace[1709898566] 'applied index is now lower than readState.Index' (duration: 127.733µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:57:52.525779Z","caller":"traceutil/trace.go:171","msg":"trace[531610966] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"187.690138ms","start":"2026-06-03T21:57:52.338069Z","end":"2026-06-03T21:57:52.525759Z","steps":["trace[531610966] 'process raft request' (duration: 187.520922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:52.525851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.896477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:57:52.525911Z","caller":"traceutil/trace.go:171","msg":"trace[27864855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5332; }","duration":"144.02123ms","start":"2026-06-03T21:57:52.381876Z","end":"2026-06-03T21:57:52.525897Z","steps":["trace[27864855] 'agreement among raft nodes before linearized reading' (duration: 143.919967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:52.525953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.020412ms","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-06-03T21:57:52.525995Z","caller":"traceutil/trace.go:171","msg":"trace[1660857740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5332; }","duration":"109.091833ms","start":"2026-06-03T21:57:52.416892Z","end":"2026-06-03T21:57:52.525984Z","steps":["trace[1660857740] 'agreement among raft nodes before linearized reading' (duration: 108.96176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:52.526091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.151341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-06-03T21:57:52.526169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.351216ms","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-06-03T21:57:52.52618Z","caller":"traceutil/trace.go:171","msg":"trace[315602357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5332; }","duration":"108.308845ms","start":"2026-06-03T21:57:52.417853Z","end":"2026-06-03T21:57:52.526162Z","steps":["trace[315602357] 'agreement among raft nodes before linearized reading' (duration: 108.019748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:52.526196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.225917ms","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-06-03T21:57:52.526204Z","caller":"traceutil/trace.go:171","msg":"trace[1068244313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5332; }","duration":"108.396947ms","start":"2026-06-03T21:57:52.417798Z","end":"2026-06-03T21:57:52.526195Z","steps":["trace[1068244313] 'agreement among raft nodes before linearized reading' (duration: 108.311305ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:52.526222Z","caller":"traceutil/trace.go:171","msg":"trace[1187707850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5332; }","duration":"109.263307ms","start":"2026-06-03T21:57:52.416953Z","end":"2026-06-03T21:57:52.526216Z","steps":["trace[1187707850] 'agreement among raft nodes before linearized reading' (duration: 109.171315ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:57.687849Z","caller":"traceutil/trace.go:171","msg":"trace[1085795053] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5663; }","duration":"152.529745ms","start":"2026-06-03T21:57:57.535294Z","end":"2026-06-03T21:57:57.687824Z","steps":["trace[1085795053] 'read index received' (duration: 148.201371ms)","trace[1085795053] 'applied index is now lower than readState.Index' (duration: 4.327194ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:57:57.687881Z","caller":"traceutil/trace.go:171","msg":"trace[2097847911] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"227.445711ms","start":"2026-06-03T21:57:57.460406Z","end":"2026-06-03T21:57:57.687851Z","steps":["trace[2097847911] 'process raft request' (duration: 223.143367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:57.688009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.695949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T21:57:57.688039Z","caller":"traceutil/trace.go:171","msg":"trace[1281980418] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5462; }","duration":"152.783831ms","start":"2026-06-03T21:57:57.535247Z","end":"2026-06-03T21:57:57.688031Z","steps":["trace[1281980418] 'agreement among raft nodes before linearized reading' (duration: 152.697829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:57.688139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.592649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-03T21:57:57.688242Z","caller":"traceutil/trace.go:171","msg":"trace[1793083903] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5462; }","duration":"136.745563ms","start":"2026-06-03T21:57:57.551478Z","end":"2026-06-03T21:57:57.688224Z","steps":["trace[1793083903] 'agreement among raft nodes before linearized reading' (duration: 136.523857ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:57.973775Z","caller":"traceutil/trace.go:171","msg":"trace[1803361246] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"273.725942ms","start":"2026-06-03T21:57:57.700009Z","end":"2026-06-03T21:57:57.973735Z","steps":["trace[1803361246] 'process raft request' (duration: 197.585627ms)","trace[1803361246] 'compare' (duration: 75.9196ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:57:58.495141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.218334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:57:58.495281Z","caller":"traceutil/trace.go:171","msg":"trace[1100991250] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"515.355197ms","start":"2026-06-03T21:57:57.979897Z","end":"2026-06-03T21:57:58.495253Z","steps":["trace[1100991250] 'process raft request' (duration: 304.924758ms)","trace[1100991250] 'compare' (duration: 210.090021ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:57:58.495475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:57.979879Z","time spent":"515.559532ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1618,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:57:58.577876Z","caller":"traceutil/trace.go:171","msg":"trace[592397937] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"597.843976ms","start":"2026-06-03T21:57:57.979985Z","end":"2026-06-03T21:57:58.577829Z","steps":["trace[592397937] 'process raft request' (duration: 597.611171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.578051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:57.979966Z","time spent":"597.995ms","remote":"127.0.0.1:51994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:57:58.578133Z","caller":"traceutil/trace.go:171","msg":"trace[921311198] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"597.992579ms","start":"2026-06-03T21:57:57.98012Z","end":"2026-06-03T21:57:58.578113Z","steps":["trace[921311198] 'process raft request' (duration: 597.64025ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:58.578248Z","caller":"traceutil/trace.go:171","msg":"trace[299130487] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"597.105438ms","start":"2026-06-03T21:57:57.981124Z","end":"2026-06-03T21:57:58.578229Z","steps":["trace[299130487] 'process raft request' (duration: 597.003615ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:58.578285Z","caller":"traceutil/trace.go:171","msg":"trace[844322989] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"597.809674ms","start":"2026-06-03T21:57:57.980445Z","end":"2026-06-03T21:57:58.578254Z","steps":["trace[844322989] 'process raft request' (duration: 597.64228ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:58.578331Z","caller":"traceutil/trace.go:171","msg":"trace[1714300910] linearizableReadLoop","detail":"{readStateIndex:5672; appliedIndex:5665; }","duration":"309.457248ms","start":"2026-06-03T21:57:58.268863Z","end":"2026-06-03T21:57:58.57832Z","steps":["trace[1714300910] 'read index received' (duration: 16.000527ms)","trace[1714300910] 'applied index is now lower than readState.Index' (duration: 293.455781ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:57:58.578354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:57.980103Z","time spent":"598.198393ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1394,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:57:58.578368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:57.981101Z","time spent":"597.19711ms","remote":"127.0.0.1:51994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1236,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:57:58.578397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:57.980435Z","time spent":"597.912187ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:57:58.578413Z","caller":"traceutil/trace.go:171","msg":"trace[1613797887] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"595.444897ms","start":"2026-06-03T21:57:57.982959Z","end":"2026-06-03T21:57:58.578404Z","steps":["trace[1613797887] 'process raft request' (duration: 595.222331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.578517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.644232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T21:57:58.578548Z","caller":"traceutil/trace.go:171","msg":"trace[1419048864] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:5470; }","duration":"309.725384ms","start":"2026-06-03T21:57:58.268813Z","end":"2026-06-03T21:57:58.578539Z","steps":["trace[1419048864] 'agreement among raft nodes before linearized reading' (duration: 309.616151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.578561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:57.982949Z","time spent":"595.56891ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:57:58.578588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.268796Z","time spent":"309.783746ms","remote":"127.0.0.1:52028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":79,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-06-03T21:57:58.578171Z","caller":"traceutil/trace.go:171","msg":"trace[1874443698] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"597.792144ms","start":"2026-06-03T21:57:57.980347Z","end":"2026-06-03T21:57:58.578139Z","steps":["trace[1874443698] 'process raft request' (duration: 597.647501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.578743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:57.980336Z","time spent":"598.335747ms","remote":"127.0.0.1:51994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:57:58.578617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.47673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:57:58.578885Z","caller":"traceutil/trace.go:171","msg":"trace[1574991639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5470; }","duration":"196.778928ms","start":"2026-06-03T21:57:58.382088Z","end":"2026-06-03T21:57:58.578867Z","steps":["trace[1574991639] 'agreement among raft nodes before linearized reading' (duration: 196.473911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.578997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.552243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T21:57:58.579069Z","caller":"traceutil/trace.go:171","msg":"trace[1528469440] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:5470; }","duration":"116.678897ms","start":"2026-06-03T21:57:58.462367Z","end":"2026-06-03T21:57:58.579046Z","steps":["trace[1528469440] 'agreement among raft nodes before linearized reading' (duration: 116.567794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.918638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.514224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:57:58.918924Z","caller":"traceutil/trace.go:171","msg":"trace[778994323] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"335.91805ms","start":"2026-06-03T21:57:58.582978Z","end":"2026-06-03T21:57:58.918896Z","steps":["trace[778994323] 'process raft request' (duration: 18.007506ms)","trace[778994323] 'compare' (duration: 317.215106ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:57:58.919094Z","caller":"traceutil/trace.go:171","msg":"trace[603069623] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"334.861964ms","start":"2026-06-03T21:57:58.584188Z","end":"2026-06-03T21:57:58.91905Z","steps":["trace[603069623] 'process raft request' (duration: 334.583658ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:58.919161Z","caller":"traceutil/trace.go:171","msg":"trace[1213175769] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"330.722123ms","start":"2026-06-03T21:57:58.588417Z","end":"2026-06-03T21:57:58.919139Z","steps":["trace[1213175769] 'process raft request' (duration: 330.671962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.919264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.584146Z","time spent":"335.026208ms","remote":"127.0.0.1:51994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1136,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:57:58.919386Z","caller":"traceutil/trace.go:171","msg":"trace[1005210003] linearizableReadLoop","detail":"{readStateIndex:5673; appliedIndex:5672; }","duration":"335.244164ms","start":"2026-06-03T21:57:58.584127Z","end":"2026-06-03T21:57:58.919371Z","steps":["trace[1005210003] 'read index received' (duration: 16.869999ms)","trace[1005210003] 'applied index is now lower than readState.Index' (duration: 318.372205ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:57:58.919409Z","caller":"traceutil/trace.go:171","msg":"trace[2137398034] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"333.112161ms","start":"2026-06-03T21:57:58.586274Z","end":"2026-06-03T21:57:58.919386Z","steps":["trace[2137398034] 'process raft request' (duration: 332.734872ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:58.91945Z","caller":"traceutil/trace.go:171","msg":"trace[1743832979] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"333.148552ms","start":"2026-06-03T21:57:58.586277Z","end":"2026-06-03T21:57:58.919426Z","steps":["trace[1743832979] 'process raft request' (duration: 332.685731ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:58.919482Z","caller":"traceutil/trace.go:171","msg":"trace[1393510219] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"333.090961ms","start":"2026-06-03T21:57:58.586357Z","end":"2026-06-03T21:57:58.919448Z","steps":["trace[1393510219] 'process raft request' (duration: 332.684661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.919497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.586257Z","time spent":"333.191574ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1676,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:57:58.919531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.586259Z","time spent":"333.235065ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1623,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:57:58.919625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.49101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-06-03T21:57:58.919596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.586342Z","time spent":"333.198674ms","remote":"127.0.0.1:51994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1373,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:57:58.919672Z","caller":"traceutil/trace.go:171","msg":"trace[1611045229] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5477; }","duration":"335.565762ms","start":"2026-06-03T21:57:58.584087Z","end":"2026-06-03T21:57:58.919652Z","steps":["trace[1611045229] 'agreement among raft nodes before linearized reading' (duration: 335.375837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.919702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.584067Z","time spent":"335.628253ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-03T21:57:58.919854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.29715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-results-cache-0\" ","response":"range_response_count:1 size:4774"} {"level":"warn","ts":"2026-06-03T21:57:58.919902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.136481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-06-03T21:57:58.919912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.693295ms","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-06-03T21:57:58.919918Z","caller":"traceutil/trace.go:171","msg":"trace[1895939066] range","detail":"{range_begin:/registry/pods/monitoring/loki-results-cache-0; range_end:; response_count:1; response_revision:5477; }","duration":"334.393032ms","start":"2026-06-03T21:57:58.585508Z","end":"2026-06-03T21:57:58.919901Z","steps":["trace[1895939066] 'agreement among raft nodes before linearized reading' (duration: 334.207978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.919919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.116949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aed141b7-db9b-45ed-8f95-7cdb3f0926f9\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-06-03T21:57:58.919954Z","caller":"traceutil/trace.go:171","msg":"trace[1890544336] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5477; }","duration":"139.225153ms","start":"2026-06-03T21:57:58.780714Z","end":"2026-06-03T21:57:58.919939Z","steps":["trace[1890544336] 'agreement among raft nodes before linearized reading' (duration: 139.06768ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:58.91997Z","caller":"traceutil/trace.go:171","msg":"trace[1004553656] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aed141b7-db9b-45ed-8f95-7cdb3f0926f9; range_end:; response_count:1; response_revision:5477; }","duration":"226.200231ms","start":"2026-06-03T21:57:58.693758Z","end":"2026-06-03T21:57:58.919959Z","steps":["trace[1004553656] 'agreement among raft nodes before linearized reading' (duration: 226.077158ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:58.919967Z","caller":"traceutil/trace.go:171","msg":"trace[438864324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5477; }","duration":"300.764508ms","start":"2026-06-03T21:57:58.619184Z","end":"2026-06-03T21:57:58.919949Z","steps":["trace[438864324] 'agreement among raft nodes before linearized reading' (duration: 300.592683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.920018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.619174Z","time spent":"300.832569ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-03T21:57:58.919973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.585491Z","time spent":"334.469164ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":4797,"request content":"key:\"/registry/pods/monitoring/loki-results-cache-0\" "} {"level":"warn","ts":"2026-06-03T21:57:58.920256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.65692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-03T21:57:58.920097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.499418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-03T21:57:58.920307Z","caller":"traceutil/trace.go:171","msg":"trace[1281924078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5477; }","duration":"306.725952ms","start":"2026-06-03T21:57:58.613566Z","end":"2026-06-03T21:57:58.920292Z","steps":["trace[1281924078] 'agreement among raft nodes before linearized reading' (duration: 306.559518ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:57:58.920322Z","caller":"traceutil/trace.go:171","msg":"trace[501610773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5477; }","duration":"304.741214ms","start":"2026-06-03T21:57:58.615562Z","end":"2026-06-03T21:57:58.920304Z","steps":["trace[501610773] 'agreement among raft nodes before linearized reading' (duration: 304.441507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.920352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.613553Z","time spent":"306.788153ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T21:57:58.920379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.615551Z","time spent":"304.811115ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T21:57:58.920386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.702164ms","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-06-03T21:57:58.920441Z","caller":"traceutil/trace.go:171","msg":"trace[771849706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5477; }","duration":"309.754825ms","start":"2026-06-03T21:57:58.610669Z","end":"2026-06-03T21:57:58.920424Z","steps":["trace[771849706] 'agreement among raft nodes before linearized reading' (duration: 309.615562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.920483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.610631Z","time spent":"309.840457ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-03T21:57:58.919471Z","caller":"traceutil/trace.go:171","msg":"trace[661247286] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"333.84634ms","start":"2026-06-03T21:57:58.585597Z","end":"2026-06-03T21:57:58.919444Z","steps":["trace[661247286] 'process raft request' (duration: 333.317767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:57:58.920642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.58557Z","time spent":"335.000346ms","remote":"127.0.0.1:52120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1413,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:57:58.919257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.588407Z","time spent":"330.809465ms","remote":"127.0.0.1:52272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3884,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:57:58.919152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:57:58.582961Z","time spent":"336.111715ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:58:09.304292Z","caller":"traceutil/trace.go:171","msg":"trace[141108707] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"119.314111ms","start":"2026-06-03T21:58:09.1849Z","end":"2026-06-03T21:58:09.304214Z","steps":["trace[141108707] 'process raft request' (duration: 41.26936ms)","trace[141108707] 'compare' (duration: 77.644301ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:58:09.596316Z","caller":"traceutil/trace.go:171","msg":"trace[358589383] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"106.131852ms","start":"2026-06-03T21:58:09.490148Z","end":"2026-06-03T21:58:09.59628Z","steps":["trace[358589383] 'process raft request' (duration: 50.35397ms)","trace[358589383] 'compare' (duration: 55.459024ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:58:37.123042Z","caller":"traceutil/trace.go:171","msg":"trace[1304769685] linearizableReadLoop","detail":"{readStateIndex:6028; appliedIndex:6027; }","duration":"122.380284ms","start":"2026-06-03T21:58:37.000636Z","end":"2026-06-03T21:58:37.123016Z","steps":["trace[1304769685] 'read index received' (duration: 98.511916ms)","trace[1304769685] 'applied index is now lower than readState.Index' (duration: 23.867408ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:58:37.123227Z","caller":"traceutil/trace.go:171","msg":"trace[331840919] transaction","detail":"{read_only:false; response_revision:5816; number_of_response:1; }","duration":"124.786473ms","start":"2026-06-03T21:58:36.998401Z","end":"2026-06-03T21:58:37.123188Z","steps":["trace[331840919] 'process raft request' (duration: 100.762932ms)","trace[331840919] 'compare' (duration: 23.721554ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:58:37.123412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.692862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-06-03T21:58:37.123497Z","caller":"traceutil/trace.go:171","msg":"trace[145063061] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5816; }","duration":"122.856856ms","start":"2026-06-03T21:58:37.000624Z","end":"2026-06-03T21:58:37.123481Z","steps":["trace[145063061] 'agreement among raft nodes before linearized reading' (duration: 122.574679ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:58:39.336552Z","caller":"traceutil/trace.go:171","msg":"trace[1914025796] linearizableReadLoop","detail":"{readStateIndex:6107; appliedIndex:6106; }","duration":"163.105892ms","start":"2026-06-03T21:58:39.173422Z","end":"2026-06-03T21:58:39.336528Z","steps":["trace[1914025796] 'read index received' (duration: 163.03212ms)","trace[1914025796] 'applied index is now lower than readState.Index' (duration: 72.452µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:58:39.336631Z","caller":"traceutil/trace.go:171","msg":"trace[1526402958] transaction","detail":"{read_only:false; response_revision:5894; number_of_response:1; }","duration":"196.081621ms","start":"2026-06-03T21:58:39.140524Z","end":"2026-06-03T21:58:39.336606Z","steps":["trace[1526402958] 'process raft request' (duration: 195.828105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:58:39.336816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.367609ms","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-06-03T21:58:39.33688Z","caller":"traceutil/trace.go:171","msg":"trace[740909992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5894; }","duration":"163.490612ms","start":"2026-06-03T21:58:39.173375Z","end":"2026-06-03T21:58:39.336865Z","steps":["trace[740909992] 'agreement among raft nodes before linearized reading' (duration: 163.252355ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:58:39.462295Z","caller":"traceutil/trace.go:171","msg":"trace[2016576581] transaction","detail":"{read_only:false; response_revision:5895; number_of_response:1; }","duration":"175.075681ms","start":"2026-06-03T21:58:39.287187Z","end":"2026-06-03T21:58:39.462263Z","steps":["trace[2016576581] 'process raft request' (duration: 169.349963ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:58:39.462298Z","caller":"traceutil/trace.go:171","msg":"trace[1563764957] linearizableReadLoop","detail":"{readStateIndex:6108; appliedIndex:6107; }","duration":"125.654954ms","start":"2026-06-03T21:58:39.336621Z","end":"2026-06-03T21:58:39.462276Z","steps":["trace[1563764957] 'read index received' (duration: 119.926966ms)","trace[1563764957] 'applied index is now lower than readState.Index' (duration: 5.727158ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:58:39.462518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.927063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-d7vjb\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-06-03T21:58:39.462549Z","caller":"traceutil/trace.go:171","msg":"trace[541710024] range","detail":"{range_begin:/registry/pods/openstack/keepalived-d7vjb; range_end:; response_count:1; response_revision:5895; }","duration":"168.994415ms","start":"2026-06-03T21:58:39.293544Z","end":"2026-06-03T21:58:39.462539Z","steps":["trace[541710024] 'agreement among raft nodes before linearized reading' (duration: 168.860901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:58:39.462704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.462916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T21:58:39.462728Z","caller":"traceutil/trace.go:171","msg":"trace[852263766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5898; }","duration":"188.539748ms","start":"2026-06-03T21:58:39.274181Z","end":"2026-06-03T21:58:39.46272Z","steps":["trace[852263766] 'agreement among raft nodes before linearized reading' (duration: 188.395615ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:58:39.462725Z","caller":"traceutil/trace.go:171","msg":"trace[778779384] transaction","detail":"{read_only:false; response_revision:5896; number_of_response:1; }","duration":"121.475383ms","start":"2026-06-03T21:58:39.341233Z","end":"2026-06-03T21:58:39.462709Z","steps":["trace[778779384] 'process raft request' (duration: 120.95622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:58:39.462837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.884195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-03T21:58:39.462852Z","caller":"traceutil/trace.go:171","msg":"trace[1779888463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5898; }","duration":"178.944426ms","start":"2026-06-03T21:58:39.283904Z","end":"2026-06-03T21:58:39.462849Z","steps":["trace[1779888463] 'agreement among raft nodes before linearized reading' (duration: 178.896244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:58:39.462925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.038858ms","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-06-03T21:58:39.462936Z","caller":"traceutil/trace.go:171","msg":"trace[749172312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5898; }","duration":"179.087859ms","start":"2026-06-03T21:58:39.283845Z","end":"2026-06-03T21:58:39.462933Z","steps":["trace[749172312] 'agreement among raft nodes before linearized reading' (duration: 179.050648ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:59:01.570399Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-06-03T21:59:01.643453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"71.528963ms","hash":3289457567,"current-db-size-bytes":58306560,"current-db-size":"58 MB","current-db-size-in-use-bytes":33779712,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-03T21:59:01.643572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3289457567,"revision":2977,"compact-revision":-1} {"level":"info","ts":"2026-06-03T22:02:05.076822Z","caller":"traceutil/trace.go:171","msg":"trace[1500162893] transaction","detail":"{read_only:false; response_revision:7264; number_of_response:1; }","duration":"105.160777ms","start":"2026-06-03T22:02:04.971639Z","end":"2026-06-03T22:02:05.0768Z","steps":["trace[1500162893] 'process raft request' (duration: 105.021834ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:02:11.935392Z","caller":"traceutil/trace.go:171","msg":"trace[731158326] transaction","detail":"{read_only:false; response_revision:7308; number_of_response:1; }","duration":"110.251079ms","start":"2026-06-03T22:02:11.825115Z","end":"2026-06-03T22:02:11.935366Z","steps":["trace[731158326] 'process raft request' (duration: 110.073055ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:01.577005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6056} {"level":"info","ts":"2026-06-03T22:04:01.676893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6056,"took":"98.015853ms","hash":2361642659,"current-db-size-bytes":58306560,"current-db-size":"58 MB","current-db-size-in-use-bytes":21270528,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-06-03T22:04:01.67695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2361642659,"revision":6056,"compact-revision":2977} {"level":"info","ts":"2026-06-03T22:04:18.957342Z","caller":"traceutil/trace.go:171","msg":"trace[1012961359] transaction","detail":"{read_only:false; response_revision:8364; number_of_response:1; }","duration":"158.422287ms","start":"2026-06-03T22:04:18.798883Z","end":"2026-06-03T22:04:18.957306Z","steps":["trace[1012961359] 'process raft request' (duration: 158.249883ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:18.980632Z","caller":"traceutil/trace.go:171","msg":"trace[1051699493] linearizableReadLoop","detail":"{readStateIndex:8663; appliedIndex:8659; }","duration":"146.60221ms","start":"2026-06-03T22:04:18.834013Z","end":"2026-06-03T22:04:18.980615Z","steps":["trace[1051699493] 'read index received' (duration: 123.232294ms)","trace[1051699493] 'applied index is now lower than readState.Index' (duration: 23.369006ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:04:18.980672Z","caller":"traceutil/trace.go:171","msg":"trace[484290471] transaction","detail":"{read_only:false; response_revision:8365; number_of_response:1; }","duration":"179.928777ms","start":"2026-06-03T22:04:18.800718Z","end":"2026-06-03T22:04:18.980647Z","steps":["trace[484290471] 'process raft request' (duration: 179.727612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:04:18.980793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.765534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T22:04:18.980818Z","caller":"traceutil/trace.go:171","msg":"trace[488012307] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:8367; }","duration":"146.849756ms","start":"2026-06-03T22:04:18.833963Z","end":"2026-06-03T22:04:18.980813Z","steps":["trace[488012307] 'agreement among raft nodes before linearized reading' (duration: 146.733793ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:18.980831Z","caller":"traceutil/trace.go:171","msg":"trace[1053676679] transaction","detail":"{read_only:false; response_revision:8366; number_of_response:1; }","duration":"171.880852ms","start":"2026-06-03T22:04:18.808939Z","end":"2026-06-03T22:04:18.98082Z","steps":["trace[1053676679] 'process raft request' (duration: 171.616555ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:18.980757Z","caller":"traceutil/trace.go:171","msg":"trace[1593243795] transaction","detail":"{read_only:false; response_revision:8367; number_of_response:1; }","duration":"171.650506ms","start":"2026-06-03T22:04:18.809066Z","end":"2026-06-03T22:04:18.980717Z","steps":["trace[1593243795] 'process raft request' (duration: 171.519623ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:19.19809Z","caller":"traceutil/trace.go:171","msg":"trace[1147408953] linearizableReadLoop","detail":"{readStateIndex:8664; appliedIndex:8663; }","duration":"164.878492ms","start":"2026-06-03T22:04:19.033185Z","end":"2026-06-03T22:04:19.198064Z","steps":["trace[1147408953] 'read index received' (duration: 164.240277ms)","trace[1147408953] 'applied index is now lower than readState.Index' (duration: 637.085µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:04:19.198101Z","caller":"traceutil/trace.go:171","msg":"trace[1590570152] transaction","detail":"{read_only:false; response_revision:8368; number_of_response:1; }","duration":"210.891717ms","start":"2026-06-03T22:04:18.98718Z","end":"2026-06-03T22:04:19.198072Z","steps":["trace[1590570152] 'process raft request' (duration: 210.316293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:04:19.198264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.043466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T22:04:19.198311Z","caller":"traceutil/trace.go:171","msg":"trace[1348545627] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8368; }","duration":"165.118758ms","start":"2026-06-03T22:04:19.033179Z","end":"2026-06-03T22:04:19.198298Z","steps":["trace[1348545627] 'agreement among raft nodes before linearized reading' (duration: 164.987685ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:31.124662Z","caller":"traceutil/trace.go:171","msg":"trace[2132578747] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"128.553253ms","start":"2026-06-03T22:04:30.996084Z","end":"2026-06-03T22:04:31.124638Z","steps":["trace[2132578747] 'process raft request' (duration: 128.373518ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:31.386038Z","caller":"traceutil/trace.go:171","msg":"trace[142470134] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"351.088337ms","start":"2026-06-03T22:04:31.03493Z","end":"2026-06-03T22:04:31.386019Z","steps":["trace[142470134] 'process raft request' (duration: 351.008245ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:31.386041Z","caller":"traceutil/trace.go:171","msg":"trace[173985040] transaction","detail":"{read_only:false; response_revision:8427; number_of_response:1; }","duration":"352.427369ms","start":"2026-06-03T22:04:31.033589Z","end":"2026-06-03T22:04:31.386016Z","steps":["trace[173985040] 'process raft request' (duration: 352.218153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:04:31.386168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:04:31.034903Z","time spent":"351.20054ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T22:04:31.38621Z","caller":"traceutil/trace.go:171","msg":"trace[680771842] linearizableReadLoop","detail":"{readStateIndex:8728; appliedIndex:8724; }","duration":"333.53303ms","start":"2026-06-03T22:04:31.052654Z","end":"2026-06-03T22:04:31.386187Z","steps":["trace[680771842] 'read index received' (duration: 71.870085ms)","trace[680771842] 'applied index is now lower than readState.Index' (duration: 261.660615ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T22:04:31.38629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.631942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T22:04:31.386282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:04:31.033564Z","time spent":"352.634804ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T22:04:31.386325Z","caller":"traceutil/trace.go:171","msg":"trace[189638156] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:8429; }","duration":"333.667253ms","start":"2026-06-03T22:04:31.052648Z","end":"2026-06-03T22:04:31.386316Z","steps":["trace[189638156] 'agreement among raft nodes before linearized reading' (duration: 333.611572ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:31.386295Z","caller":"traceutil/trace.go:171","msg":"trace[1923434288] transaction","detail":"{read_only:false; response_revision:8429; number_of_response:1; }","duration":"351.20619ms","start":"2026-06-03T22:04:31.035048Z","end":"2026-06-03T22:04:31.386255Z","steps":["trace[1923434288] 'process raft request' (duration: 350.941483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:04:31.386353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:04:31.052612Z","time spent":"333.733435ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-06-03T22:04:31.386445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.802443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-03T22:04:31.386468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:04:31.035037Z","time spent":"351.335032ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T22:04:31.386489Z","caller":"traceutil/trace.go:171","msg":"trace[1144950815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8429; }","duration":"136.924166ms","start":"2026-06-03T22:04:31.249554Z","end":"2026-06-03T22:04:31.386478Z","steps":["trace[1144950815] 'agreement among raft nodes before linearized reading' (duration: 136.780062ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:31.531195Z","caller":"traceutil/trace.go:171","msg":"trace[1115392504] transaction","detail":"{read_only:false; response_revision:8431; number_of_response:1; }","duration":"139.394294ms","start":"2026-06-03T22:04:31.391747Z","end":"2026-06-03T22:04:31.531142Z","steps":["trace[1115392504] 'process raft request' (duration: 103.152934ms)","trace[1115392504] 'compare' (duration: 35.980634ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:04:31.531143Z","caller":"traceutil/trace.go:171","msg":"trace[1465299113] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"139.143798ms","start":"2026-06-03T22:04:31.391974Z","end":"2026-06-03T22:04:31.531118Z","steps":["trace[1465299113] 'process raft request' (duration: 139.049085ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:31.531338Z","caller":"traceutil/trace.go:171","msg":"trace[1422698319] transaction","detail":"{read_only:false; response_revision:8434; number_of_response:1; }","duration":"138.445042ms","start":"2026-06-03T22:04:31.392881Z","end":"2026-06-03T22:04:31.531326Z","steps":["trace[1422698319] 'process raft request' (duration: 138.328719ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:31.531351Z","caller":"traceutil/trace.go:171","msg":"trace[1986559001] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"138.449812ms","start":"2026-06-03T22:04:31.39287Z","end":"2026-06-03T22:04:31.53132Z","steps":["trace[1986559001] 'process raft request' (duration: 138.204956ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:04:33.533608Z","caller":"traceutil/trace.go:171","msg":"trace[4147622] transaction","detail":"{read_only:false; response_revision:8438; number_of_response:1; }","duration":"139.683383ms","start":"2026-06-03T22:04:33.3939Z","end":"2026-06-03T22:04:33.533583Z","steps":["trace[4147622] 'process raft request' (duration: 40.629478ms)","trace[4147622] 'compare' (duration: 98.763318ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:04:33.53359Z","caller":"traceutil/trace.go:171","msg":"trace[1748644947] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"139.57249ms","start":"2026-06-03T22:04:33.393996Z","end":"2026-06-03T22:04:33.533569Z","steps":["trace[1748644947] 'process raft request' (duration: 139.442547ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:05:01.298635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.538052ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-03T22:05:01.298716Z","caller":"traceutil/trace.go:171","msg":"trace[2088657672] linearizableReadLoop","detail":"{readStateIndex:8948; appliedIndex:8947; }","duration":"261.301766ms","start":"2026-06-03T22:05:01.037402Z","end":"2026-06-03T22:05:01.298704Z","steps":["trace[2088657672] 'read index received' (duration: 46.531µs)","trace[2088657672] 'applied index is now lower than readState.Index' (duration: 261.252295ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T22:05:01.298781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.382367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T22:05:01.298795Z","caller":"traceutil/trace.go:171","msg":"trace[289366799] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:8641; }","duration":"261.445859ms","start":"2026-06-03T22:05:01.037344Z","end":"2026-06-03T22:05:01.29879Z","steps":["trace[289366799] 'agreement among raft nodes before linearized reading' (duration: 261.388007ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:13.822311Z","caller":"traceutil/trace.go:171","msg":"trace[1469810866] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8747; }","duration":"111.212941ms","start":"2026-06-03T22:05:13.71107Z","end":"2026-06-03T22:05:13.822283Z","steps":["trace[1469810866] 'process raft request' (duration: 88.501959ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:13.822512Z","caller":"traceutil/trace.go:171","msg":"trace[1071854036] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"101.086115ms","start":"2026-06-03T22:05:13.721292Z","end":"2026-06-03T22:05:13.822378Z","steps":["trace[1071854036] 'process raft request' (duration: 100.88058ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:14.343846Z","caller":"traceutil/trace.go:171","msg":"trace[2063183529] transaction","detail":"{read_only:false; response_revision:8757; number_of_response:1; }","duration":"106.768662ms","start":"2026-06-03T22:05:14.237053Z","end":"2026-06-03T22:05:14.343822Z","steps":["trace[2063183529] 'process raft request' (duration: 106.615289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:05:14.917176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.71592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T22:05:14.917401Z","caller":"traceutil/trace.go:171","msg":"trace[513182852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8757; }","duration":"535.994727ms","start":"2026-06-03T22:05:14.381381Z","end":"2026-06-03T22:05:14.917376Z","steps":["trace[513182852] 'range keys from in-memory index tree' (duration: 535.627347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:05:14.917595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:05:14.381358Z","time spent":"536.216032ms","remote":"127.0.0.1:51848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T22:05:14.959537Z","caller":"traceutil/trace.go:171","msg":"trace[1788768258] linearizableReadLoop","detail":"{readStateIndex:9071; appliedIndex:9070; }","duration":"236.555096ms","start":"2026-06-03T22:05:14.722957Z","end":"2026-06-03T22:05:14.959512Z","steps":["trace[1788768258] 'read index received' (duration: 236.340501ms)","trace[1788768258] 'applied index is now lower than readState.Index' (duration: 214.045µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T22:05:14.960602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.616411ms","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-06-03T22:05:14.960645Z","caller":"traceutil/trace.go:171","msg":"trace[170694230] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8758; }","duration":"237.722754ms","start":"2026-06-03T22:05:14.722913Z","end":"2026-06-03T22:05:14.960636Z","steps":["trace[170694230] 'agreement among raft nodes before linearized reading' (duration: 237.050438ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:14.959897Z","caller":"traceutil/trace.go:171","msg":"trace[926721605] transaction","detail":"{read_only:false; response_revision:8758; number_of_response:1; }","duration":"265.518539ms","start":"2026-06-03T22:05:14.694366Z","end":"2026-06-03T22:05:14.959885Z","steps":["trace[926721605] 'process raft request' (duration: 264.994596ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:15.293962Z","caller":"traceutil/trace.go:171","msg":"trace[999066651] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"156.48993ms","start":"2026-06-03T22:05:15.137411Z","end":"2026-06-03T22:05:15.293901Z","steps":["trace[999066651] 'process raft request' (duration: 155.903936ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:15.871024Z","caller":"traceutil/trace.go:171","msg":"trace[528033026] linearizableReadLoop","detail":"{readStateIndex:9075; appliedIndex:9074; }","duration":"269.635488ms","start":"2026-06-03T22:05:15.601369Z","end":"2026-06-03T22:05:15.871004Z","steps":["trace[528033026] 'read index received' (duration: 269.584377ms)","trace[528033026] 'applied index is now lower than readState.Index' (duration: 49.981µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:05:15.871072Z","caller":"traceutil/trace.go:171","msg":"trace[5707022] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"295.293481ms","start":"2026-06-03T22:05:15.575749Z","end":"2026-06-03T22:05:15.871042Z","steps":["trace[5707022] 'process raft request' (duration: 295.135517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:05:15.871195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.820812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-03T22:05:15.871227Z","caller":"traceutil/trace.go:171","msg":"trace[1052023175] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:8762; }","duration":"269.905604ms","start":"2026-06-03T22:05:15.601313Z","end":"2026-06-03T22:05:15.871219Z","steps":["trace[1052023175] 'agreement among raft nodes before linearized reading' (duration: 269.783271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:05:15.895449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.908404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-77cb7ccf84-b65fz.18b5b16079d2d7c1\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-06-03T22:05:15.895512Z","caller":"traceutil/trace.go:171","msg":"trace[1227543668] range","detail":"{range_begin:/registry/events/openstack/barbican-api-77cb7ccf84-b65fz.18b5b16079d2d7c1; range_end:; response_count:1; response_revision:8764; }","duration":"278.990686ms","start":"2026-06-03T22:05:15.616502Z","end":"2026-06-03T22:05:15.895493Z","steps":["trace[1227543668] 'agreement among raft nodes before linearized reading' (duration: 278.824872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:05:15.896302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.763674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224956"} {"level":"info","ts":"2026-06-03T22:05:15.89638Z","caller":"traceutil/trace.go:171","msg":"trace[1306280350] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8764; }","duration":"286.880447ms","start":"2026-06-03T22:05:15.609482Z","end":"2026-06-03T22:05:15.896362Z","steps":["trace[1306280350] 'agreement among raft nodes before linearized reading' (duration: 285.892363ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:16.070669Z","caller":"traceutil/trace.go:171","msg":"trace[1047315088] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"173.004602ms","start":"2026-06-03T22:05:15.897638Z","end":"2026-06-03T22:05:16.070642Z","steps":["trace[1047315088] 'process raft request' (duration: 172.754435ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:16.070707Z","caller":"traceutil/trace.go:171","msg":"trace[982042865] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"171.634889ms","start":"2026-06-03T22:05:15.899047Z","end":"2026-06-03T22:05:16.070682Z","steps":["trace[982042865] 'process raft request' (duration: 171.534397ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:16.070901Z","caller":"traceutil/trace.go:171","msg":"trace[50948571] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"171.717651ms","start":"2026-06-03T22:05:15.899165Z","end":"2026-06-03T22:05:16.070882Z","steps":["trace[50948571] 'process raft request' (duration: 171.472355ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:16.071083Z","caller":"traceutil/trace.go:171","msg":"trace[1846941137] transaction","detail":"{read_only:false; response_revision:8768; number_of_response:1; }","duration":"171.29174ms","start":"2026-06-03T22:05:15.899766Z","end":"2026-06-03T22:05:16.071057Z","steps":["trace[1846941137] 'process raft request' (duration: 171.051144ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:16.071273Z","caller":"traceutil/trace.go:171","msg":"trace[1578869491] linearizableReadLoop","detail":"{readStateIndex:9084; appliedIndex:9078; }","duration":"143.283459ms","start":"2026-06-03T22:05:15.927973Z","end":"2026-06-03T22:05:16.071257Z","steps":["trace[1578869491] 'read index received' (duration: 142.582581ms)","trace[1578869491] 'applied index is now lower than readState.Index' (duration: 699.688µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:05:16.071289Z","caller":"traceutil/trace.go:171","msg":"trace[771497609] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"169.427955ms","start":"2026-06-03T22:05:15.901848Z","end":"2026-06-03T22:05:16.071276Z","steps":["trace[771497609] 'process raft request' (duration: 169.309943ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:16.071276Z","caller":"traceutil/trace.go:171","msg":"trace[1829086957] transaction","detail":"{read_only:false; response_revision:8769; number_of_response:1; }","duration":"171.481234ms","start":"2026-06-03T22:05:15.899783Z","end":"2026-06-03T22:05:16.071264Z","steps":["trace[1829086957] 'process raft request' (duration: 171.202397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:05:16.071451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.754266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-06-03T22:05:16.071496Z","caller":"traceutil/trace.go:171","msg":"trace[25309456] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8770; }","duration":"144.829557ms","start":"2026-06-03T22:05:15.926655Z","end":"2026-06-03T22:05:16.071484Z","steps":["trace[25309456] 'agreement among raft nodes before linearized reading' (duration: 144.652753ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:16.350909Z","caller":"traceutil/trace.go:171","msg":"trace[150304256] transaction","detail":"{read_only:false; response_revision:8771; number_of_response:1; }","duration":"421.950367ms","start":"2026-06-03T22:05:15.928945Z","end":"2026-06-03T22:05:16.350895Z","steps":["trace[150304256] 'process raft request' (duration: 421.728411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:05:16.351053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:05:15.928935Z","time spent":"422.03706ms","remote":"127.0.0.1:51866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T22:05:16.351357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.599928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228751"} {"level":"info","ts":"2026-06-03T22:05:16.351418Z","caller":"traceutil/trace.go:171","msg":"trace[1359908225] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8771; }","duration":"416.69185ms","start":"2026-06-03T22:05:15.93471Z","end":"2026-06-03T22:05:16.351402Z","steps":["trace[1359908225] 'agreement among raft nodes before linearized reading' (duration: 416.27499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:05:16.351456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:05:15.934662Z","time spent":"416.783302ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228774,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-06-03T22:05:16.350852Z","caller":"traceutil/trace.go:171","msg":"trace[850881542] linearizableReadLoop","detail":"{readStateIndex:9085; appliedIndex:9084; }","duration":"279.515309ms","start":"2026-06-03T22:05:16.071314Z","end":"2026-06-03T22:05:16.350829Z","steps":["trace[850881542] 'read index received' (duration: 279.310163ms)","trace[850881542] 'applied index is now lower than readState.Index' (duration: 203.835µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T22:05:16.351775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.924325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-06-03T22:05:16.351824Z","caller":"traceutil/trace.go:171","msg":"trace[1591234298] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8771; }","duration":"256.016218ms","start":"2026-06-03T22:05:16.095788Z","end":"2026-06-03T22:05:16.351804Z","steps":["trace[1591234298] 'agreement among raft nodes before linearized reading' (duration: 255.810953ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:31.657463Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241f.wal"} {"level":"info","ts":"2026-06-03T22:05:54.408933Z","caller":"traceutil/trace.go:171","msg":"trace[2137891200] linearizableReadLoop","detail":"{readStateIndex:9439; appliedIndex:9438; }","duration":"133.327758ms","start":"2026-06-03T22:05:54.275576Z","end":"2026-06-03T22:05:54.408904Z","steps":["trace[2137891200] 'read index received' (duration: 133.237476ms)","trace[2137891200] 'applied index is now lower than readState.Index' (duration: 88.832µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T22:05:54.409189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.584785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T22:05:54.409258Z","caller":"traceutil/trace.go:171","msg":"trace[1652009735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9116; }","duration":"133.717788ms","start":"2026-06-03T22:05:54.275526Z","end":"2026-06-03T22:05:54.409244Z","steps":["trace[1652009735] 'agreement among raft nodes before linearized reading' (duration: 133.491703ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:54.408976Z","caller":"traceutil/trace.go:171","msg":"trace[1928696016] transaction","detail":"{read_only:false; response_revision:9116; number_of_response:1; }","duration":"135.439919ms","start":"2026-06-03T22:05:54.273515Z","end":"2026-06-03T22:05:54.408955Z","steps":["trace[1928696016] 'process raft request' (duration: 134.997029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:05:54.413498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.674774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-03T22:05:54.413551Z","caller":"traceutil/trace.go:171","msg":"trace[292496106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9117; }","duration":"137.730145ms","start":"2026-06-03T22:05:54.275809Z","end":"2026-06-03T22:05:54.413539Z","steps":["trace[292496106] 'agreement among raft nodes before linearized reading' (duration: 137.587332ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:05:54.413686Z","caller":"traceutil/trace.go:171","msg":"trace[118381042] transaction","detail":"{read_only:false; response_revision:9117; number_of_response:1; }","duration":"137.331185ms","start":"2026-06-03T22:05:54.276328Z","end":"2026-06-03T22:05:54.413659Z","steps":["trace[118381042] 'process raft request' (duration: 136.987067ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:06:19.873315Z","caller":"traceutil/trace.go:171","msg":"trace[575472657] transaction","detail":"{read_only:false; response_revision:9439; number_of_response:1; }","duration":"182.948392ms","start":"2026-06-03T22:06:19.690319Z","end":"2026-06-03T22:06:19.873267Z","steps":["trace[575472657] 'process raft request' (duration: 182.670466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:06:32.492681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.747331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T22:06:32.493013Z","caller":"traceutil/trace.go:171","msg":"trace[1633445921] transaction","detail":"{read_only:false; response_revision:9510; number_of_response:1; }","duration":"313.934239ms","start":"2026-06-03T22:06:32.178899Z","end":"2026-06-03T22:06:32.492833Z","steps":["trace[1633445921] 'process raft request' (duration: 184.897571ms)","trace[1633445921] 'compare' (duration: 128.386872ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T22:06:32.493089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:06:32.178874Z","time spent":"314.182785ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T22:06:32.515933Z","caller":"traceutil/trace.go:171","msg":"trace[1766164089] linearizableReadLoop","detail":"{readStateIndex:9844; appliedIndex:9843; }","duration":"132.89707ms","start":"2026-06-03T22:06:32.383019Z","end":"2026-06-03T22:06:32.515916Z","steps":["trace[1766164089] 'read index received' (duration: 132.693435ms)","trace[1766164089] 'applied index is now lower than readState.Index' (duration: 202.775µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T22:06:32.516033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.015764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T22:06:32.516043Z","caller":"traceutil/trace.go:171","msg":"trace[700982972] transaction","detail":"{read_only:false; response_revision:9511; number_of_response:1; }","duration":"246.926904ms","start":"2026-06-03T22:06:32.269083Z","end":"2026-06-03T22:06:32.51601Z","steps":["trace[700982972] 'process raft request' (duration: 246.694269ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:06:32.516074Z","caller":"traceutil/trace.go:171","msg":"trace[1128315012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9511; }","duration":"133.095306ms","start":"2026-06-03T22:06:32.382964Z","end":"2026-06-03T22:06:32.516059Z","steps":["trace[1128315012] 'agreement among raft nodes before linearized reading' (duration: 133.019764ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:06:32.733174Z","caller":"traceutil/trace.go:171","msg":"trace[1182570672] linearizableReadLoop","detail":"{readStateIndex:9845; appliedIndex:9844; }","duration":"150.98886ms","start":"2026-06-03T22:06:32.582158Z","end":"2026-06-03T22:06:32.733147Z","steps":["trace[1182570672] 'read index received' (duration: 145.761033ms)","trace[1182570672] 'applied index is now lower than readState.Index' (duration: 5.226677ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:06:32.733286Z","caller":"traceutil/trace.go:171","msg":"trace[727702263] transaction","detail":"{read_only:false; response_revision:9512; number_of_response:1; }","duration":"151.550224ms","start":"2026-06-03T22:06:32.581705Z","end":"2026-06-03T22:06:32.733255Z","steps":["trace[727702263] 'process raft request' (duration: 146.252476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:06:32.733367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.172694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T22:06:32.733408Z","caller":"traceutil/trace.go:171","msg":"trace[464830968] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:9512; }","duration":"151.263257ms","start":"2026-06-03T22:06:32.582134Z","end":"2026-06-03T22:06:32.733397Z","steps":["trace[464830968] 'agreement among raft nodes before linearized reading' (duration: 151.157304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:06:32.733512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.242849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-03T22:06:32.73358Z","caller":"traceutil/trace.go:171","msg":"trace[2102811513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9512; }","duration":"135.339371ms","start":"2026-06-03T22:06:32.598224Z","end":"2026-06-03T22:06:32.733563Z","steps":["trace[2102811513] 'agreement among raft nodes before linearized reading' (duration: 135.111305ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:06:50.911872Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-03T22:06:50.917138Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-03T22:06:50.917227Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-03T22:09:01.586507Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8104} {"level":"info","ts":"2026-06-03T22:09:01.651234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8104,"took":"62.405037ms","hash":4044411011,"current-db-size-bytes":58306560,"current-db-size":"58 MB","current-db-size-in-use-bytes":29143040,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-03T22:09:01.651352Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4044411011,"revision":8104,"compact-revision":6056} {"level":"info","ts":"2026-06-03T22:09:51.924011Z","caller":"traceutil/trace.go:171","msg":"trace[1793201766] linearizableReadLoop","detail":"{readStateIndex:11612; appliedIndex:11611; }","duration":"143.088847ms","start":"2026-06-03T22:09:51.780889Z","end":"2026-06-03T22:09:51.923978Z","steps":["trace[1793201766] 'read index received' (duration: 100.59628ms)","trace[1793201766] 'applied index is now lower than readState.Index' (duration: 42.491297ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:09:51.924266Z","caller":"traceutil/trace.go:171","msg":"trace[1271699947] transaction","detail":"{read_only:false; response_revision:11230; number_of_response:1; }","duration":"243.206516ms","start":"2026-06-03T22:09:51.68103Z","end":"2026-06-03T22:09:51.924236Z","steps":["trace[1271699947] 'process raft request' (duration: 200.463603ms)","trace[1271699947] 'compare' (duration: 42.313043ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T22:09:51.924375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.477206ms","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-06-03T22:09:51.924542Z","caller":"traceutil/trace.go:171","msg":"trace[1423056443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11230; }","duration":"143.68351ms","start":"2026-06-03T22:09:51.780834Z","end":"2026-06-03T22:09:51.924517Z","steps":["trace[1423056443] 'agreement among raft nodes before linearized reading' (duration: 143.382603ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:10:10.140634Z","caller":"traceutil/trace.go:171","msg":"trace[911091970] linearizableReadLoop","detail":"{readStateIndex:11714; appliedIndex:11713; }","duration":"131.537688ms","start":"2026-06-03T22:10:10.009074Z","end":"2026-06-03T22:10:10.140611Z","steps":["trace[911091970] 'read index received' (duration: 131.413055ms)","trace[911091970] 'applied index is now lower than readState.Index' (duration: 123.593µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:10:10.140696Z","caller":"traceutil/trace.go:171","msg":"trace[417290135] transaction","detail":"{read_only:false; response_revision:11329; number_of_response:1; }","duration":"134.321705ms","start":"2026-06-03T22:10:10.006337Z","end":"2026-06-03T22:10:10.140659Z","steps":["trace[417290135] 'process raft request' (duration: 134.12349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:10:10.140882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.441252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-03T22:10:10.140906Z","caller":"traceutil/trace.go:171","msg":"trace[1986053731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11329; }","duration":"130.519244ms","start":"2026-06-03T22:10:10.010379Z","end":"2026-06-03T22:10:10.140898Z","steps":["trace[1986053731] 'agreement among raft nodes before linearized reading' (duration: 130.392621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:10:10.140908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.503716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T22:10:10.140958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.536837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T22:10:10.140918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.788324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-03T22:10:10.141007Z","caller":"traceutil/trace.go:171","msg":"trace[279522766] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11329; }","duration":"114.624549ms","start":"2026-06-03T22:10:10.026356Z","end":"2026-06-03T22:10:10.140981Z","steps":["trace[279522766] 'agreement among raft nodes before linearized reading' (duration: 114.447145ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:10:10.141051Z","caller":"traceutil/trace.go:171","msg":"trace[1198743871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11329; }","duration":"131.991249ms","start":"2026-06-03T22:10:10.009041Z","end":"2026-06-03T22:10:10.141032Z","steps":["trace[1198743871] 'agreement among raft nodes before linearized reading' (duration: 131.702982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:10:10.141075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.606212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T22:10:10.141144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.175837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-03T22:10:10.141175Z","caller":"traceutil/trace.go:171","msg":"trace[155931377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11329; }","duration":"128.247128ms","start":"2026-06-03T22:10:10.012919Z","end":"2026-06-03T22:10:10.141166Z","steps":["trace[155931377] 'agreement among raft nodes before linearized reading' (duration: 128.117695ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:10:10.141182Z","caller":"traceutil/trace.go:171","msg":"trace[919007019] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11329; }","duration":"115.756286ms","start":"2026-06-03T22:10:10.0254Z","end":"2026-06-03T22:10:10.141157Z","steps":["trace[919007019] 'agreement among raft nodes before linearized reading' (duration: 115.599932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:10:10.141159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.334974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-03T22:10:10.141287Z","caller":"traceutil/trace.go:171","msg":"trace[557324365] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11329; }","duration":"112.531219ms","start":"2026-06-03T22:10:10.028749Z","end":"2026-06-03T22:10:10.14128Z","steps":["trace[557324365] 'agreement among raft nodes before linearized reading' (duration: 112.124419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:10:10.141272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.055269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T22:10:10.1414Z","caller":"traceutil/trace.go:171","msg":"trace[330702321] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11329; }","duration":"115.241704ms","start":"2026-06-03T22:10:10.026134Z","end":"2026-06-03T22:10:10.141376Z","steps":["trace[330702321] 'agreement among raft nodes before linearized reading' (duration: 114.948157ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:10:10.140996Z","caller":"traceutil/trace.go:171","msg":"trace[1859899214] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11329; }","duration":"114.608339ms","start":"2026-06-03T22:10:10.026376Z","end":"2026-06-03T22:10:10.140984Z","steps":["trace[1859899214] 'agreement among raft nodes before linearized reading' (duration: 114.519057ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:14:01.593416Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10659} {"level":"info","ts":"2026-06-03T22:14:01.676899Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10659,"took":"80.867776ms","hash":2051012942,"current-db-size-bytes":58306560,"current-db-size":"58 MB","current-db-size-in-use-bytes":27607040,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-03T22:14:01.676956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2051012942,"revision":10659,"compact-revision":8104} {"level":"info","ts":"2026-06-03T22:16:10.28137Z","caller":"traceutil/trace.go:171","msg":"trace[1258587133] transaction","detail":"{read_only:false; response_revision:13783; number_of_response:1; }","duration":"185.739783ms","start":"2026-06-03T22:16:10.095608Z","end":"2026-06-03T22:16:10.281348Z","steps":["trace[1258587133] 'process raft request' (duration: 185.59879ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:16:28.770245Z","caller":"traceutil/trace.go:171","msg":"trace[1915396463] transaction","detail":"{read_only:false; response_revision:13878; number_of_response:1; }","duration":"190.287651ms","start":"2026-06-03T22:16:28.579928Z","end":"2026-06-03T22:16:28.770216Z","steps":["trace[1915396463] 'process raft request' (duration: 190.107857ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:16:28.773633Z","caller":"traceutil/trace.go:171","msg":"trace[344945714] linearizableReadLoop","detail":"{readStateIndex:14354; appliedIndex:14353; }","duration":"140.435465ms","start":"2026-06-03T22:16:28.633178Z","end":"2026-06-03T22:16:28.773613Z","steps":["trace[344945714] 'read index received' (duration: 137.019243ms)","trace[344945714] 'applied index is now lower than readState.Index' (duration: 3.415042ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:16:28.773714Z","caller":"traceutil/trace.go:171","msg":"trace[696543831] transaction","detail":"{read_only:false; response_revision:13879; number_of_response:1; }","duration":"192.525514ms","start":"2026-06-03T22:16:28.581151Z","end":"2026-06-03T22:16:28.773676Z","steps":["trace[696543831] 'process raft request' (duration: 192.362569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:16:28.773887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.690691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-06-03T22:16:28.773981Z","caller":"traceutil/trace.go:171","msg":"trace[1634771801] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:13879; }","duration":"140.830344ms","start":"2026-06-03T22:16:28.633128Z","end":"2026-06-03T22:16:28.773959Z","steps":["trace[1634771801] 'agreement among raft nodes before linearized reading' (duration: 140.568428ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:16:36.598848Z","caller":"traceutil/trace.go:171","msg":"trace[171299893] transaction","detail":"{read_only:false; response_revision:13918; number_of_response:1; }","duration":"224.008907ms","start":"2026-06-03T22:16:36.374761Z","end":"2026-06-03T22:16:36.59877Z","steps":["trace[171299893] 'process raft request' (duration: 198.693749ms)","trace[171299893] 'compare' (duration: 25.026061ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:16:45.232302Z","caller":"traceutil/trace.go:171","msg":"trace[401237103] transaction","detail":"{read_only:false; response_revision:13976; number_of_response:1; }","duration":"409.842483ms","start":"2026-06-03T22:16:44.822435Z","end":"2026-06-03T22:16:45.232278Z","steps":["trace[401237103] 'process raft request' (duration: 409.654719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:16:45.232443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:16:44.822415Z","time spent":"409.957706ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T22:16:45.271333Z","caller":"traceutil/trace.go:171","msg":"trace[60207912] linearizableReadLoop","detail":"{readStateIndex:14457; appliedIndex:14455; }","duration":"442.191296ms","start":"2026-06-03T22:16:44.829094Z","end":"2026-06-03T22:16:45.271285Z","steps":["trace[60207912] 'read index received' (duration: 403.038682ms)","trace[60207912] 'applied index is now lower than readState.Index' (duration: 39.151064ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:16:45.271467Z","caller":"traceutil/trace.go:171","msg":"trace[990408514] transaction","detail":"{read_only:false; response_revision:13977; number_of_response:1; }","duration":"448.934446ms","start":"2026-06-03T22:16:44.822521Z","end":"2026-06-03T22:16:45.271456Z","steps":["trace[990408514] 'process raft request' (duration: 442.880563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:16:45.271587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:16:44.822495Z","time spent":"449.005758ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T22:16:45.271628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.260653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T22:16:45.271727Z","caller":"traceutil/trace.go:171","msg":"trace[1526326943] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:13977; }","duration":"302.410807ms","start":"2026-06-03T22:16:44.969293Z","end":"2026-06-03T22:16:45.271704Z","steps":["trace[1526326943] 'agreement among raft nodes before linearized reading' (duration: 302.247223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:16:45.271845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:16:44.969263Z","time spent":"302.569701ms","remote":"127.0.0.1:47160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-03T22:16:45.27175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.618486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-03T22:16:45.272042Z","caller":"traceutil/trace.go:171","msg":"trace[160275603] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:13977; }","duration":"442.843022ms","start":"2026-06-03T22:16:44.829062Z","end":"2026-06-03T22:16:45.271905Z","steps":["trace[160275603] 'agreement among raft nodes before linearized reading' (duration: 442.498944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:16:45.272215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T22:16:44.829053Z","time spent":"443.046236ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-03T22:17:06.056624Z","caller":"traceutil/trace.go:171","msg":"trace[187625405] transaction","detail":"{read_only:false; response_revision:14277; number_of_response:1; }","duration":"123.138685ms","start":"2026-06-03T22:17:05.933428Z","end":"2026-06-03T22:17:06.056567Z","steps":["trace[187625405] 'process raft request' (duration: 122.92283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:17:06.448197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.895565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-03T22:17:06.448286Z","caller":"traceutil/trace.go:171","msg":"trace[992234242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14277; }","duration":"191.051498ms","start":"2026-06-03T22:17:06.257216Z","end":"2026-06-03T22:17:06.448268Z","steps":["trace[992234242] 'range keys from in-memory index tree' (duration: 190.743821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:17:06.448573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.848646ms","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-06-03T22:17:06.449146Z","caller":"traceutil/trace.go:171","msg":"trace[1583125291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14277; }","duration":"250.480691ms","start":"2026-06-03T22:17:06.198644Z","end":"2026-06-03T22:17:06.449124Z","steps":["trace[1583125291] 'range keys from in-memory index tree' (duration: 249.493028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:17:06.448617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.72579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-03T22:17:06.449435Z","caller":"traceutil/trace.go:171","msg":"trace[1700021446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14277; }","duration":"210.517689ms","start":"2026-06-03T22:17:06.238856Z","end":"2026-06-03T22:17:06.449374Z","steps":["trace[1700021446] 'range keys from in-memory index tree' (duration: 209.595377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:17:06.448683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.849922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T22:17:06.449621Z","caller":"traceutil/trace.go:171","msg":"trace[1755333944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14277; }","duration":"193.795505ms","start":"2026-06-03T22:17:06.25579Z","end":"2026-06-03T22:17:06.449586Z","steps":["trace[1755333944] 'range keys from in-memory index tree' (duration: 192.723048ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:17:57.754565Z","caller":"traceutil/trace.go:171","msg":"trace[1129599661] linearizableReadLoop","detail":"{readStateIndex:15415; appliedIndex:15414; }","duration":"118.743564ms","start":"2026-06-03T22:17:57.635796Z","end":"2026-06-03T22:17:57.75454Z","steps":["trace[1129599661] 'read index received' (duration: 118.518148ms)","trace[1129599661] 'applied index is now lower than readState.Index' (duration: 224.446µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:17:57.754661Z","caller":"traceutil/trace.go:171","msg":"trace[1461335762] transaction","detail":"{read_only:false; response_revision:14906; number_of_response:1; }","duration":"126.384983ms","start":"2026-06-03T22:17:57.628223Z","end":"2026-06-03T22:17:57.754608Z","steps":["trace[1461335762] 'process raft request' (duration: 126.158228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:17:57.75506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.233985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-2g6wz\" ","response":"range_response_count:1 size:22096"} {"level":"info","ts":"2026-06-03T22:17:57.75514Z","caller":"traceutil/trace.go:171","msg":"trace[201901071] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-2g6wz; range_end:; response_count:1; response_revision:14906; }","duration":"119.356247ms","start":"2026-06-03T22:17:57.635765Z","end":"2026-06-03T22:17:57.755121Z","steps":["trace[201901071] 'agreement among raft nodes before linearized reading' (duration: 118.870036ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:18:21.802258Z","caller":"traceutil/trace.go:171","msg":"trace[131560913] linearizableReadLoop","detail":"{readStateIndex:15547; appliedIndex:15546; }","duration":"231.070463ms","start":"2026-06-03T22:18:21.571164Z","end":"2026-06-03T22:18:21.802234Z","steps":["trace[131560913] 'read index received' (duration: 230.984911ms)","trace[131560913] 'applied index is now lower than readState.Index' (duration: 84.472µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:18:21.802296Z","caller":"traceutil/trace.go:171","msg":"trace[1926289793] transaction","detail":"{read_only:false; response_revision:15033; number_of_response:1; }","duration":"235.327464ms","start":"2026-06-03T22:18:21.566942Z","end":"2026-06-03T22:18:21.802269Z","steps":["trace[1926289793] 'process raft request' (duration: 235.15945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:18:21.802449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.275168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-03T22:18:21.802508Z","caller":"traceutil/trace.go:171","msg":"trace[976405472] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15033; }","duration":"231.35709ms","start":"2026-06-03T22:18:21.571116Z","end":"2026-06-03T22:18:21.802473Z","steps":["trace[976405472] 'agreement among raft nodes before linearized reading' (duration: 231.224067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:18:21.803686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.744048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-06-03T22:18:21.803749Z","caller":"traceutil/trace.go:171","msg":"trace[2050133369] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15034; }","duration":"228.862551ms","start":"2026-06-03T22:18:21.574871Z","end":"2026-06-03T22:18:21.803733Z","steps":["trace[2050133369] 'agreement among raft nodes before linearized reading' (duration: 228.679627ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:18:21.80395Z","caller":"traceutil/trace.go:171","msg":"trace[656001976] transaction","detail":"{read_only:false; response_revision:15034; number_of_response:1; }","duration":"224.521699ms","start":"2026-06-03T22:18:21.579419Z","end":"2026-06-03T22:18:21.803941Z","steps":["trace[656001976] 'process raft request' (duration: 224.032307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:18:21.804093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.299011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-03T22:18:21.804112Z","caller":"traceutil/trace.go:171","msg":"trace[1417721550] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15034; }","duration":"226.341722ms","start":"2026-06-03T22:18:21.577765Z","end":"2026-06-03T22:18:21.804106Z","steps":["trace[1417721550] 'agreement among raft nodes before linearized reading' (duration: 226.27045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:18:21.804314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.788566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T22:18:21.804358Z","caller":"traceutil/trace.go:171","msg":"trace[1187090723] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15034; }","duration":"227.855288ms","start":"2026-06-03T22:18:21.576487Z","end":"2026-06-03T22:18:21.804342Z","steps":["trace[1187090723] 'agreement among raft nodes before linearized reading' (duration: 227.764516ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:18:22.941866Z","caller":"traceutil/trace.go:171","msg":"trace[102780630] linearizableReadLoop","detail":"{readStateIndex:15550; appliedIndex:15549; }","duration":"127.009767ms","start":"2026-06-03T22:18:22.814831Z","end":"2026-06-03T22:18:22.94184Z","steps":["trace[102780630] 'read index received' (duration: 126.815913ms)","trace[102780630] 'applied index is now lower than readState.Index' (duration: 192.674µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:18:22.941916Z","caller":"traceutil/trace.go:171","msg":"trace[1273387288] transaction","detail":"{read_only:false; response_revision:15036; number_of_response:1; }","duration":"144.569022ms","start":"2026-06-03T22:18:22.797315Z","end":"2026-06-03T22:18:22.941884Z","steps":["trace[1273387288] 'process raft request' (duration: 144.325757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:18:22.942169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.313104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-03T22:18:22.942186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.078816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-03T22:18:22.94221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.187558ms","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-06-03T22:18:22.942252Z","caller":"traceutil/trace.go:171","msg":"trace[1103737256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15036; }","duration":"126.279311ms","start":"2026-06-03T22:18:22.815962Z","end":"2026-06-03T22:18:22.942241Z","steps":["trace[1103737256] 'agreement among raft nodes before linearized reading' (duration: 126.032335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:18:22.942164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.644618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-03T22:18:22.942258Z","caller":"traceutil/trace.go:171","msg":"trace[802967066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15036; }","duration":"127.462999ms","start":"2026-06-03T22:18:22.814764Z","end":"2026-06-03T22:18:22.942227Z","steps":["trace[802967066] 'agreement among raft nodes before linearized reading' (duration: 127.212183ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:18:22.942294Z","caller":"traceutil/trace.go:171","msg":"trace[980615108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15036; }","duration":"123.810972ms","start":"2026-06-03T22:18:22.818472Z","end":"2026-06-03T22:18:22.942283Z","steps":["trace[980615108] 'agreement among raft nodes before linearized reading' (duration: 123.543525ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:18:22.942257Z","caller":"traceutil/trace.go:171","msg":"trace[764936694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15036; }","duration":"126.161358ms","start":"2026-06-03T22:18:22.816082Z","end":"2026-06-03T22:18:22.942243Z","steps":["trace[764936694] 'agreement among raft nodes before linearized reading' (duration: 125.955913ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:18:23.161257Z","caller":"traceutil/trace.go:171","msg":"trace[232286357] transaction","detail":"{read_only:false; response_revision:15037; number_of_response:1; }","duration":"214.770969ms","start":"2026-06-03T22:18:22.946446Z","end":"2026-06-03T22:18:23.161217Z","steps":["trace[232286357] 'process raft request' (duration: 158.494842ms)","trace[232286357] 'compare' (duration: 56.138054ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:18:23.162624Z","caller":"traceutil/trace.go:171","msg":"trace[734499729] transaction","detail":"{read_only:false; response_revision:15039; number_of_response:1; }","duration":"215.557047ms","start":"2026-06-03T22:18:22.947037Z","end":"2026-06-03T22:18:23.162594Z","steps":["trace[734499729] 'process raft request' (duration: 215.440725ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:18:23.162641Z","caller":"traceutil/trace.go:171","msg":"trace[1918503261] transaction","detail":"{read_only:false; response_revision:15038; number_of_response:1; }","duration":"215.71938ms","start":"2026-06-03T22:18:22.946881Z","end":"2026-06-03T22:18:23.162601Z","steps":["trace[1918503261] 'process raft request' (duration: 215.345452ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:18:23.162915Z","caller":"traceutil/trace.go:171","msg":"trace[1407510040] linearizableReadLoop","detail":"{readStateIndex:15553; appliedIndex:15550; }","duration":"212.140046ms","start":"2026-06-03T22:18:22.95076Z","end":"2026-06-03T22:18:23.1629Z","steps":["trace[1407510040] 'read index received' (duration: 154.192219ms)","trace[1407510040] 'applied index is now lower than readState.Index' (duration: 57.946767ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:18:23.162961Z","caller":"traceutil/trace.go:171","msg":"trace[57931435] transaction","detail":"{read_only:false; response_revision:15040; number_of_response:1; }","duration":"203.107084ms","start":"2026-06-03T22:18:22.959839Z","end":"2026-06-03T22:18:23.162946Z","steps":["trace[57931435] 'process raft request' (duration: 202.694064ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:18:23.163007Z","caller":"traceutil/trace.go:171","msg":"trace[2041860746] transaction","detail":"{read_only:false; response_revision:15041; number_of_response:1; }","duration":"161.765318ms","start":"2026-06-03T22:18:23.001228Z","end":"2026-06-03T22:18:23.162994Z","steps":["trace[2041860746] 'process raft request' (duration: 161.602834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:18:23.163157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.377053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T22:18:23.163206Z","caller":"traceutil/trace.go:171","msg":"trace[993896483] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:15041; }","duration":"212.462544ms","start":"2026-06-03T22:18:22.950729Z","end":"2026-06-03T22:18:23.163191Z","steps":["trace[993896483] 'agreement among raft nodes before linearized reading' (duration: 212.361392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:18:23.163233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.117177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T22:18:23.163288Z","caller":"traceutil/trace.go:171","msg":"trace[1935932898] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15041; }","duration":"165.215759ms","start":"2026-06-03T22:18:22.99806Z","end":"2026-06-03T22:18:23.163276Z","steps":["trace[1935932898] 'agreement among raft nodes before linearized reading' (duration: 165.113337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:18:23.163315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.811446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-06-03T22:18:23.163315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.053525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-03T22:18:23.163372Z","caller":"traceutil/trace.go:171","msg":"trace[401105448] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15041; }","duration":"163.902378ms","start":"2026-06-03T22:18:22.999457Z","end":"2026-06-03T22:18:23.163359Z","steps":["trace[401105448] 'agreement among raft nodes before linearized reading' (duration: 163.728894ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:18:23.163388Z","caller":"traceutil/trace.go:171","msg":"trace[2050200895] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15041; }","duration":"162.158707ms","start":"2026-06-03T22:18:23.001209Z","end":"2026-06-03T22:18:23.163368Z","steps":["trace[2050200895] 'agreement among raft nodes before linearized reading' (duration: 161.969543ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:19:01.599267Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12829} {"level":"info","ts":"2026-06-03T22:19:01.680514Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12829,"took":"78.297188ms","hash":938095680,"current-db-size-bytes":58306560,"current-db-size":"58 MB","current-db-size-in-use-bytes":30285824,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-03T22:19:01.680604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":938095680,"revision":12829,"compact-revision":10659} {"level":"warn","ts":"2026-06-03T22:19:30.920726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.630043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T22:19:30.920819Z","caller":"traceutil/trace.go:171","msg":"trace[559102816] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:15452; }","duration":"101.775816ms","start":"2026-06-03T22:19:30.819026Z","end":"2026-06-03T22:19:30.920801Z","steps":["trace[559102816] 'count revisions from in-memory index tree' (duration: 101.572272ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:21:24.201207Z","caller":"traceutil/trace.go:171","msg":"trace[1856039318] linearizableReadLoop","detail":"{readStateIndex:17052; appliedIndex:17051; }","duration":"142.648698ms","start":"2026-06-03T22:21:24.058528Z","end":"2026-06-03T22:21:24.201177Z","steps":["trace[1856039318] 'read index received' (duration: 70.031248ms)","trace[1856039318] 'applied index is now lower than readState.Index' (duration: 72.61601ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T22:21:24.20128Z","caller":"traceutil/trace.go:171","msg":"trace[1612892343] transaction","detail":"{read_only:false; response_revision:16492; number_of_response:1; }","duration":"144.642396ms","start":"2026-06-03T22:21:24.056598Z","end":"2026-06-03T22:21:24.20124Z","steps":["trace[1612892343] 'process raft request' (duration: 72.084008ms)","trace[1612892343] 'compare' (duration: 72.365763ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T22:21:24.201541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.049618ms","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-06-03T22:21:24.201627Z","caller":"traceutil/trace.go:171","msg":"trace[368695581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16492; }","duration":"129.17101ms","start":"2026-06-03T22:21:24.072435Z","end":"2026-06-03T22:21:24.201607Z","steps":["trace[368695581] 'agreement among raft nodes before linearized reading' (duration: 128.962845ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:21:24.202089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.526654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-03T22:21:24.20222Z","caller":"traceutil/trace.go:171","msg":"trace[1942676825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16492; }","duration":"123.701938ms","start":"2026-06-03T22:21:24.078487Z","end":"2026-06-03T22:21:24.202189Z","steps":["trace[1942676825] 'agreement among raft nodes before linearized reading' (duration: 123.291388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T22:21:24.202497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.945039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:89 size:897977"} {"level":"info","ts":"2026-06-03T22:21:24.202574Z","caller":"traceutil/trace.go:171","msg":"trace[1962292524] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:89; response_revision:16492; }","duration":"144.074102ms","start":"2026-06-03T22:21:24.058478Z","end":"2026-06-03T22:21:24.202552Z","steps":["trace[1962292524] 'agreement among raft nodes before linearized reading' (duration: 142.859383ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:24:01.605027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15254} {"level":"info","ts":"2026-06-03T22:24:01.6943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15254,"took":"86.495722ms","hash":1744156366,"current-db-size-bytes":58306560,"current-db-size":"58 MB","current-db-size-in-use-bytes":31850496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-03T22:24:01.694353Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1744156366,"revision":15254,"compact-revision":12829} {"level":"info","ts":"2026-06-03T22:27:25.088077Z","caller":"traceutil/trace.go:171","msg":"trace[40939665] transaction","detail":"{read_only:false; response_revision:18625; number_of_response:1; }","duration":"106.804265ms","start":"2026-06-03T22:27:24.981247Z","end":"2026-06-03T22:27:25.088051Z","steps":["trace[40939665] 'process raft request' (duration: 106.645971ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T22:29:01.615455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17535} {"level":"info","ts":"2026-06-03T22:29:01.710748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17535,"took":"92.824966ms","hash":1538658255,"current-db-size-bytes":58306560,"current-db-size":"58 MB","current-db-size-in-use-bytes":26906624,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-03T22:29:01.710808Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1538658255,"revision":17535,"compact-revision":15254} {"level":"info","ts":"2026-06-03T22:29:44.20977Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-03T22:29:44.215598Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-03T22:29:44.215738Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}