{"level":"warn","ts":"2026-06-05T23:30:52.851944Z","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-05T23:30:52.852029Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-05T23:30:52.852084Z","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-05T23:30:52.852092Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-06-05T23:30:52.852114Z","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-05T23:30:52.852724Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-06-05T23:30:52.852795Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-05T23:30:52.856464Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.469505ms"} {"level":"info","ts":"2026-06-05T23:30:52.863992Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-06-05T23:30:52.864146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T23:30:52.864189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-06-05T23:30:52.864212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T23:30:52.864227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-06-05T23:30:52.864297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-06-05T23:30:52.870537Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T23:30:52.875442Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T23:30:52.877047Z","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-05T23:30:52.879285Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T23:30:52.879899Z","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-05T23:30:52.879803Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T23:30:52.879966Z","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-05T23:30:52.880008Z","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-05T23:30:52.880974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-06-05T23:30:52.881081Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-06-05T23:30:52.882119Z","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-05T23:30:52.882185Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-06-05T23:30:52.882329Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T23:30:52.882372Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T23:30:52.882207Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-06-05T23:30:53.665171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T23:30:53.66523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T23:30:53.665279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-06-05T23:30:53.665299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-06-05T23:30:53.665316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-06-05T23:30:53.665333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-06-05T23:30:53.665352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-06-05T23:30:53.666266Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T23:30:53.66712Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T23:30:53.667162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T23:30:53.667241Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T23:30:53.667483Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T23:30:53.667494Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T23:30:53.667514Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T23:30:53.667617Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T23:30:53.667657Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T23:30:53.671027Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T23:30:53.671661Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"warn","ts":"2026-06-05T23:31:12.230499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.406141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:31:12.230774Z","caller":"traceutil/trace.go:171","msg":"trace[1320651137] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"346.668394ms","start":"2026-06-05T23:31:11.884065Z","end":"2026-06-05T23:31:12.230733Z","steps":["trace[1320651137] 'process raft request' (duration: 187.406385ms)","trace[1320651137] 'compare' (duration: 158.150005ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:31:12.231001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:31:11.884047Z","time spent":"346.845457ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:31:17.583092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.684158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-05T23:31:17.583139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.971276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-06-05T23:31:17.583197Z","caller":"traceutil/trace.go:171","msg":"trace[179966579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"172.837111ms","start":"2026-06-05T23:31:17.410338Z","end":"2026-06-05T23:31:17.583175Z","steps":["trace[179966579] 'range keys from in-memory index tree' (duration: 172.596757ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:17.583216Z","caller":"traceutil/trace.go:171","msg":"trace[859721268] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:426; }","duration":"187.060248ms","start":"2026-06-05T23:31:17.396138Z","end":"2026-06-05T23:31:17.583198Z","steps":["trace[859721268] 'range keys from in-memory index tree' (duration: 186.856433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:31:31.626128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.267758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:31:31.626297Z","caller":"traceutil/trace.go:171","msg":"trace[1505040226] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"178.110655ms","start":"2026-06-05T23:31:31.448157Z","end":"2026-06-05T23:31:31.626268Z","steps":["trace[1505040226] 'process raft request' (duration: 71.602302ms)","trace[1505040226] 'compare' (duration: 106.087263ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:31:31.65264Z","caller":"traceutil/trace.go:171","msg":"trace[1367811818] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"199.913283ms","start":"2026-06-05T23:31:31.452703Z","end":"2026-06-05T23:31:31.652616Z","steps":["trace[1367811818] 'process raft request' (duration: 199.846962ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.652682Z","caller":"traceutil/trace.go:171","msg":"trace[1083757619] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"201.796845ms","start":"2026-06-05T23:31:31.450848Z","end":"2026-06-05T23:31:31.652645Z","steps":["trace[1083757619] 'process raft request' (duration: 201.57748ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.653154Z","caller":"traceutil/trace.go:171","msg":"trace[13976930] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"198.560925ms","start":"2026-06-05T23:31:31.454577Z","end":"2026-06-05T23:31:31.653137Z","steps":["trace[13976930] 'process raft request' (duration: 198.009683ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.653219Z","caller":"traceutil/trace.go:171","msg":"trace[123287307] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"198.151995ms","start":"2026-06-05T23:31:31.455028Z","end":"2026-06-05T23:31:31.65318Z","steps":["trace[123287307] 'process raft request' (duration: 197.985841ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.653592Z","caller":"traceutil/trace.go:171","msg":"trace[1061278512] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"196.352407ms","start":"2026-06-05T23:31:31.457226Z","end":"2026-06-05T23:31:31.653578Z","steps":["trace[1061278512] 'process raft request' (duration: 196.100492ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.65364Z","caller":"traceutil/trace.go:171","msg":"trace[1949518254] transaction","detail":"{read_only:false; number_of_response:1; response_revision:527; }","duration":"197.732676ms","start":"2026-06-05T23:31:31.45589Z","end":"2026-06-05T23:31:31.653623Z","steps":["trace[1949518254] 'process raft request' (duration: 197.248226ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.653712Z","caller":"traceutil/trace.go:171","msg":"trace[966059896] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"193.399674ms","start":"2026-06-05T23:31:31.460302Z","end":"2026-06-05T23:31:31.653701Z","steps":["trace[966059896] 'process raft request' (duration: 193.20643ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.653716Z","caller":"traceutil/trace.go:171","msg":"trace[1034721992] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"194.17712ms","start":"2026-06-05T23:31:31.459485Z","end":"2026-06-05T23:31:31.653662Z","steps":["trace[1034721992] 'process raft request' (duration: 193.985166ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.65395Z","caller":"traceutil/trace.go:171","msg":"trace[1640133859] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"191.648216ms","start":"2026-06-05T23:31:31.462195Z","end":"2026-06-05T23:31:31.653843Z","steps":["trace[1640133859] 'process raft request' (duration: 191.430461ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.653985Z","caller":"traceutil/trace.go:171","msg":"trace[1333027901] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"152.077814ms","start":"2026-06-05T23:31:31.501871Z","end":"2026-06-05T23:31:31.653949Z","steps":["trace[1333027901] 'process raft request' (duration: 151.92918ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.654012Z","caller":"traceutil/trace.go:171","msg":"trace[1602253602] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"180.063417ms","start":"2026-06-05T23:31:31.473937Z","end":"2026-06-05T23:31:31.654001Z","steps":["trace[1602253602] 'process raft request' (duration: 179.798681ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.65396Z","caller":"traceutil/trace.go:171","msg":"trace[1539734993] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"187.364774ms","start":"2026-06-05T23:31:31.466518Z","end":"2026-06-05T23:31:31.653883Z","steps":["trace[1539734993] 'process raft request' (duration: 187.163119ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:31.654412Z","caller":"traceutil/trace.go:171","msg":"trace[1924864718] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"196.596092ms","start":"2026-06-05T23:31:31.457786Z","end":"2026-06-05T23:31:31.654382Z","steps":["trace[1924864718] 'process raft request' (duration: 195.643301ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:37.644053Z","caller":"traceutil/trace.go:171","msg":"trace[1287959352] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"148.324225ms","start":"2026-06-05T23:31:37.495694Z","end":"2026-06-05T23:31:37.644018Z","steps":["trace[1287959352] 'process raft request' (duration: 147.60075ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:37.829201Z","caller":"traceutil/trace.go:171","msg":"trace[1159609518] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"117.707629ms","start":"2026-06-05T23:31:37.711469Z","end":"2026-06-05T23:31:37.829177Z","steps":["trace[1159609518] 'process raft request' (duration: 117.653618ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:31:37.829221Z","caller":"traceutil/trace.go:171","msg":"trace[2094664867] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"123.555756ms","start":"2026-06-05T23:31:37.705626Z","end":"2026-06-05T23:31:37.829181Z","steps":["trace[2094664867] 'read index received' (duration: 95.422184ms)","trace[2094664867] 'applied index is now lower than readState.Index' (duration: 28.130342ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:31:37.829231Z","caller":"traceutil/trace.go:171","msg":"trace[1750177687] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"128.681748ms","start":"2026-06-05T23:31:37.700506Z","end":"2026-06-05T23:31:37.829187Z","steps":["trace[1750177687] 'process raft request' (duration: 100.562417ms)","trace[1750177687] 'compare' (duration: 27.910667ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:31:37.82939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.74342ms","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-05T23:31:37.829456Z","caller":"traceutil/trace.go:171","msg":"trace[762557493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"123.852903ms","start":"2026-06-05T23:31:37.705591Z","end":"2026-06-05T23:31:37.829444Z","steps":["trace[762557493] 'agreement among raft nodes before linearized reading' (duration: 123.688109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:31:38.275945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.820512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:31:38.276064Z","caller":"traceutil/trace.go:171","msg":"trace[1997001895] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"435.741503ms","start":"2026-06-05T23:31:37.840303Z","end":"2026-06-05T23:31:38.276045Z","steps":["trace[1997001895] 'read index received' (duration: 265.637466ms)","trace[1997001895] 'applied index is now lower than readState.Index' (duration: 170.102867ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:31:38.276126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.819785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:31:38.276126Z","caller":"traceutil/trace.go:171","msg":"trace[1531960130] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"441.430797ms","start":"2026-06-05T23:31:37.834659Z","end":"2026-06-05T23:31:38.27609Z","steps":["trace[1531960130] 'process raft request' (duration: 271.37453ms)","trace[1531960130] 'compare' (duration: 169.596317ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:31:38.276162Z","caller":"traceutil/trace.go:171","msg":"trace[1330222999] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:620; }","duration":"435.878366ms","start":"2026-06-05T23:31:37.840271Z","end":"2026-06-05T23:31:38.27615Z","steps":["trace[1330222999] 'agreement among raft nodes before linearized reading' (duration: 435.824205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:31:38.276209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:31:37.840256Z","time spent":"435.945048ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":27,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-06-05T23:31:38.276278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:31:37.834639Z","time spent":"441.559999ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:31:38.284043Z","caller":"traceutil/trace.go:171","msg":"trace[1496992646] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"440.850715ms","start":"2026-06-05T23:31:37.843161Z","end":"2026-06-05T23:31:38.284012Z","steps":["trace[1496992646] 'process raft request' (duration: 440.621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:31:38.284236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:31:37.843142Z","time spent":"440.988527ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:33:12.399729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.284866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-70871811-e42c-4d4a-898c-0e0cc24cac8e\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-05T23:33:12.399831Z","caller":"traceutil/trace.go:171","msg":"trace[1772967168] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-70871811-e42c-4d4a-898c-0e0cc24cac8e; range_end:; response_count:1; response_revision:1873; }","duration":"177.46993ms","start":"2026-06-05T23:33:12.222343Z","end":"2026-06-05T23:33:12.399813Z","steps":["trace[1772967168] 'range keys from in-memory index tree' (duration: 177.070992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:33:12.956437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.41461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:33:12.956568Z","caller":"traceutil/trace.go:171","msg":"trace[995685790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1882; }","duration":"146.603854ms","start":"2026-06-05T23:33:12.809935Z","end":"2026-06-05T23:33:12.956539Z","steps":["trace[995685790] 'range keys from in-memory index tree' (duration: 146.310508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:33:26.254902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.569044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T23:33:26.255034Z","caller":"traceutil/trace.go:171","msg":"trace[421616218] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1986; }","duration":"112.767099ms","start":"2026-06-05T23:33:26.142242Z","end":"2026-06-05T23:33:26.255009Z","steps":["trace[421616218] 'count revisions from in-memory index tree' (duration: 112.495993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:33:56.555425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.544156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:33:56.555651Z","caller":"traceutil/trace.go:171","msg":"trace[1982118000] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"181.35935ms","start":"2026-06-05T23:33:56.374269Z","end":"2026-06-05T23:33:56.555628Z","steps":["trace[1982118000] 'process raft request' (duration: 52.486917ms)","trace[1982118000] 'compare' (duration: 128.189999ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:33:56.942724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.961157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:33:56.942869Z","caller":"traceutil/trace.go:171","msg":"trace[60040427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2169; }","duration":"133.16788ms","start":"2026-06-05T23:33:56.809676Z","end":"2026-06-05T23:33:56.942844Z","steps":["trace[60040427] 'range keys from in-memory index tree' (duration: 132.794393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:33:56.943085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.113624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-05T23:33:56.943237Z","caller":"traceutil/trace.go:171","msg":"trace[1261501348] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:2169; }","duration":"120.517511ms","start":"2026-06-05T23:33:56.822686Z","end":"2026-06-05T23:33:56.943204Z","steps":["trace[1261501348] 'range keys from in-memory index tree' (duration: 119.829068ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:34:02.695085Z","caller":"traceutil/trace.go:171","msg":"trace[1214907962] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"117.479609ms","start":"2026-06-05T23:34:02.57755Z","end":"2026-06-05T23:34:02.695029Z","steps":["trace[1214907962] 'process raft request' (duration: 117.285085ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:34:08.452837Z","caller":"traceutil/trace.go:171","msg":"trace[1705239005] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"150.196381ms","start":"2026-06-05T23:34:08.302616Z","end":"2026-06-05T23:34:08.452812Z","steps":["trace[1705239005] 'process raft request' (duration: 149.984037ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:34:55.269916Z","caller":"traceutil/trace.go:171","msg":"trace[1432476119] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"185.34656ms","start":"2026-06-05T23:34:55.084547Z","end":"2026-06-05T23:34:55.269893Z","steps":["trace[1432476119] 'process raft request' (duration: 185.188567ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:39:31.082199Z","caller":"traceutil/trace.go:171","msg":"trace[1499034595] linearizableReadLoop","detail":"{readStateIndex:5095; appliedIndex:5094; }","duration":"141.581239ms","start":"2026-06-05T23:39:30.940578Z","end":"2026-06-05T23:39:31.082159Z","steps":["trace[1499034595] 'read index received' (duration: 113.487467ms)","trace[1499034595] 'applied index is now lower than readState.Index' (duration: 28.092352ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:39:31.082296Z","caller":"traceutil/trace.go:171","msg":"trace[629577394] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"142.873656ms","start":"2026-06-05T23:39:30.93936Z","end":"2026-06-05T23:39:31.082234Z","steps":["trace[629577394] 'process raft request' (duration: 114.681832ms)","trace[629577394] 'compare' (duration: 27.942239ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:39:31.082651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.046378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62895"} {"level":"info","ts":"2026-06-05T23:39:31.082747Z","caller":"traceutil/trace.go:171","msg":"trace[1144385157] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4908; }","duration":"142.251263ms","start":"2026-06-05T23:39:30.940474Z","end":"2026-06-05T23:39:31.082725Z","steps":["trace[1144385157] 'agreement among raft nodes before linearized reading' (duration: 141.864705ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:39:33.482333Z","caller":"traceutil/trace.go:171","msg":"trace[1956355790] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"105.448945ms","start":"2026-06-05T23:39:33.376848Z","end":"2026-06-05T23:39:33.482297Z","steps":["trace[1956355790] 'process raft request' (duration: 105.223681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:39:49.901892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.703218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:39:49.902058Z","caller":"traceutil/trace.go:171","msg":"trace[462849897] linearizableReadLoop","detail":"{readStateIndex:5574; appliedIndex:5573; }","duration":"161.30555ms","start":"2026-06-05T23:39:49.740733Z","end":"2026-06-05T23:39:49.902039Z","steps":["trace[462849897] 'read index received' (duration: 273.966µs)","trace[462849897] 'applied index is now lower than readState.Index' (duration: 161.030534ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:39:49.902066Z","caller":"traceutil/trace.go:171","msg":"trace[582556702] transaction","detail":"{read_only:false; response_revision:5371; number_of_response:1; }","duration":"240.715855ms","start":"2026-06-05T23:39:49.661319Z","end":"2026-06-05T23:39:49.902035Z","steps":["trace[582556702] 'process raft request' (duration: 79.750032ms)","trace[582556702] 'compare' (duration: 160.476024ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:39:49.902359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.619727ms","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-05T23:39:49.902396Z","caller":"traceutil/trace.go:171","msg":"trace[2031889085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5371; }","duration":"161.723979ms","start":"2026-06-05T23:39:49.740662Z","end":"2026-06-05T23:39:49.902386Z","steps":["trace[2031889085] 'agreement among raft nodes before linearized reading' (duration: 161.491864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:39:58.216029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.326496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97e37598-7c8f-4458-8858-52ddcb81c372\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-05T23:39:58.21621Z","caller":"traceutil/trace.go:171","msg":"trace[229382840] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97e37598-7c8f-4458-8858-52ddcb81c372; range_end:; response_count:1; response_revision:5535; }","duration":"205.578071ms","start":"2026-06-05T23:39:58.010601Z","end":"2026-06-05T23:39:58.21618Z","steps":["trace[229382840] 'range keys from in-memory index tree' (duration: 205.0506ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:39:58.216657Z","caller":"traceutil/trace.go:171","msg":"trace[2080683350] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"147.49903ms","start":"2026-06-05T23:39:58.069095Z","end":"2026-06-05T23:39:58.216594Z","steps":["trace[2080683350] 'process raft request' (duration: 143.522739ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:39:58.216722Z","caller":"traceutil/trace.go:171","msg":"trace[2008563973] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"135.923035ms","start":"2026-06-05T23:39:58.080758Z","end":"2026-06-05T23:39:58.216681Z","steps":["trace[2008563973] 'process raft request' (duration: 135.772841ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:40:53.698585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2998} {"level":"info","ts":"2026-06-05T23:40:53.763653Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2998,"took":"63.083489ms","hash":1846159225,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":33849344,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-05T23:40:53.763711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1846159225,"revision":2998,"compact-revision":-1} {"level":"warn","ts":"2026-06-05T23:41:02.263762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.11549ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-05T23:45:53.706464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6087} {"level":"info","ts":"2026-06-05T23:45:53.803505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6087,"took":"94.542228ms","hash":497125847,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":22044672,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-05T23:45:53.803594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":497125847,"revision":6087,"compact-revision":2998} {"level":"info","ts":"2026-06-05T23:46:08.650782Z","caller":"traceutil/trace.go:171","msg":"trace[1752318297] linearizableReadLoop","detail":"{readStateIndex:8692; appliedIndex:8691; }","duration":"127.064171ms","start":"2026-06-05T23:46:08.523682Z","end":"2026-06-05T23:46:08.650746Z","steps":["trace[1752318297] 'read index received' (duration: 126.856516ms)","trace[1752318297] 'applied index is now lower than readState.Index' (duration: 205.574µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:46:08.650884Z","caller":"traceutil/trace.go:171","msg":"trace[222777529] transaction","detail":"{read_only:false; response_revision:8395; number_of_response:1; }","duration":"262.897431ms","start":"2026-06-05T23:46:08.387953Z","end":"2026-06-05T23:46:08.650851Z","steps":["trace[222777529] 'process raft request' (duration: 262.586144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:46:08.651124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.407917ms","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-05T23:46:08.651226Z","caller":"traceutil/trace.go:171","msg":"trace[1270788108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8395; }","duration":"127.567872ms","start":"2026-06-05T23:46:08.523638Z","end":"2026-06-05T23:46:08.651205Z","steps":["trace[1270788108] 'agreement among raft nodes before linearized reading' (duration: 127.230115ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:46:08.788403Z","caller":"traceutil/trace.go:171","msg":"trace[677445183] transaction","detail":"{read_only:false; response_revision:8396; number_of_response:1; }","duration":"235.346969ms","start":"2026-06-05T23:46:08.553031Z","end":"2026-06-05T23:46:08.788378Z","steps":["trace[677445183] 'process raft request' (duration: 235.151705ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:46:08.78929Z","caller":"traceutil/trace.go:171","msg":"trace[1462107584] transaction","detail":"{read_only:false; response_revision:8397; number_of_response:1; }","duration":"133.43866ms","start":"2026-06-05T23:46:08.655815Z","end":"2026-06-05T23:46:08.789253Z","steps":["trace[1462107584] 'process raft request' (duration: 133.209526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:46:20.354409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.452786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T23:46:20.354514Z","caller":"traceutil/trace.go:171","msg":"trace[1142576777] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:8454; }","duration":"195.595639ms","start":"2026-06-05T23:46:20.158896Z","end":"2026-06-05T23:46:20.354491Z","steps":["trace[1142576777] 'count revisions from in-memory index tree' (duration: 195.349854ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:46:50.168019Z","caller":"traceutil/trace.go:171","msg":"trace[1652858478] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"260.776378ms","start":"2026-06-05T23:46:49.907203Z","end":"2026-06-05T23:46:50.16798Z","steps":["trace[1652858478] 'process raft request' (duration: 260.536333ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:46:50.333866Z","caller":"traceutil/trace.go:171","msg":"trace[568406805] linearizableReadLoop","detail":"{readStateIndex:8972; appliedIndex:8970; }","duration":"338.813589ms","start":"2026-06-05T23:46:49.994995Z","end":"2026-06-05T23:46:50.333809Z","steps":["trace[568406805] 'read index received' (duration: 172.693152ms)","trace[568406805] 'applied index is now lower than readState.Index' (duration: 166.118857ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:46:50.334023Z","caller":"traceutil/trace.go:171","msg":"trace[277908211] transaction","detail":"{read_only:false; response_revision:8665; number_of_response:1; }","duration":"425.875384ms","start":"2026-06-05T23:46:49.908055Z","end":"2026-06-05T23:46:50.333931Z","steps":["trace[277908211] 'process raft request' (duration: 425.582168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:46:50.334294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:46:49.908009Z","time spent":"426.14177ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:46:50.335751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.733448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lv6hj\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-06-05T23:46:50.335818Z","caller":"traceutil/trace.go:171","msg":"trace[569352544] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lv6hj; range_end:; response_count:1; response_revision:8665; }","duration":"340.87453ms","start":"2026-06-05T23:46:49.994928Z","end":"2026-06-05T23:46:50.335803Z","steps":["trace[569352544] 'agreement among raft nodes before linearized reading' (duration: 339.049043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:46:50.335857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:46:49.994901Z","time spent":"340.947412ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6467,"request content":"key:\"/registry/pods/openstack/keepalived-lv6hj\" "} {"level":"info","ts":"2026-06-05T23:46:53.912873Z","caller":"traceutil/trace.go:171","msg":"trace[148967616] linearizableReadLoop","detail":"{readStateIndex:8990; appliedIndex:8989; }","duration":"104.381748ms","start":"2026-06-05T23:46:53.808468Z","end":"2026-06-05T23:46:53.912849Z","steps":["trace[148967616] 'read index received' (duration: 104.142363ms)","trace[148967616] 'applied index is now lower than readState.Index' (duration: 238.324µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:46:53.91297Z","caller":"traceutil/trace.go:171","msg":"trace[660211990] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"129.043141ms","start":"2026-06-05T23:46:53.783898Z","end":"2026-06-05T23:46:53.912941Z","steps":["trace[660211990] 'process raft request' (duration: 128.784986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:46:53.913115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.621703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:46:53.913193Z","caller":"traceutil/trace.go:171","msg":"trace[1561230982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8682; }","duration":"104.768156ms","start":"2026-06-05T23:46:53.808405Z","end":"2026-06-05T23:46:53.913173Z","steps":["trace[1561230982] 'agreement among raft nodes before linearized reading' (duration: 104.583932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:47:02.669269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.240321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:47:02.669433Z","caller":"traceutil/trace.go:171","msg":"trace[442544880] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"211.927332ms","start":"2026-06-05T23:47:02.457466Z","end":"2026-06-05T23:47:02.669394Z","steps":["trace[442544880] 'process raft request' (duration: 91.439325ms)","trace[442544880] 'compare' (duration: 119.828683ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:47:02.677182Z","caller":"traceutil/trace.go:171","msg":"trace[1310111273] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"211.562354ms","start":"2026-06-05T23:47:02.465578Z","end":"2026-06-05T23:47:02.67714Z","steps":["trace[1310111273] 'process raft request' (duration: 211.474112ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:47:02.677264Z","caller":"traceutil/trace.go:171","msg":"trace[1143780210] transaction","detail":"{read_only:false; response_revision:8771; number_of_response:1; }","duration":"219.701011ms","start":"2026-06-05T23:47:02.457492Z","end":"2026-06-05T23:47:02.677193Z","steps":["trace[1143780210] 'process raft request' (duration: 219.359043ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:47:03.231493Z","caller":"traceutil/trace.go:171","msg":"trace[29107012] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"102.530691ms","start":"2026-06-05T23:47:03.128919Z","end":"2026-06-05T23:47:03.23145Z","steps":["trace[29107012] 'process raft request' (duration: 64.799411ms)","trace[29107012] 'compare' (duration: 37.531036ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:47:21.864892Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242e.wal"} {"level":"info","ts":"2026-06-05T23:48:22.063256Z","caller":"traceutil/trace.go:171","msg":"trace[1059530859] transaction","detail":"{read_only:false; response_revision:9528; number_of_response:1; }","duration":"159.021965ms","start":"2026-06-05T23:48:21.904202Z","end":"2026-06-05T23:48:22.063224Z","steps":["trace[1059530859] 'process raft request' (duration: 158.82173ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:48:38.605124Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-05T23:48:38.610405Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-05T23:48:38.610506Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-05T23:50:53.712466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8181} {"level":"info","ts":"2026-06-05T23:50:53.778735Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8181,"took":"63.541388ms","hash":2554891847,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":28512256,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-05T23:50:53.778816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2554891847,"revision":8181,"compact-revision":6087} {"level":"info","ts":"2026-06-05T23:54:04.077761Z","caller":"traceutil/trace.go:171","msg":"trace[1135360964] transaction","detail":"{read_only:false; response_revision:11968; number_of_response:1; }","duration":"144.51346ms","start":"2026-06-05T23:54:03.933219Z","end":"2026-06-05T23:54:04.077732Z","steps":["trace[1135360964] 'process raft request' (duration: 144.333917ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:55:53.71925Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10525} {"level":"info","ts":"2026-06-05T23:55:53.802994Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10525,"took":"80.573596ms","hash":4288240937,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":26533888,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-05T23:55:53.803075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4288240937,"revision":10525,"compact-revision":8181} {"level":"info","ts":"2026-06-06T00:00:41.535051Z","caller":"traceutil/trace.go:171","msg":"trace[381677663] transaction","detail":"{read_only:false; response_revision:14618; number_of_response:1; }","duration":"421.751324ms","start":"2026-06-06T00:00:41.113273Z","end":"2026-06-06T00:00:41.535024Z","steps":["trace[381677663] 'process raft request' (duration: 421.53091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:00:41.53528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:00:41.113255Z","time spent":"421.914487ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:00:52.752857Z","caller":"traceutil/trace.go:171","msg":"trace[189178247] transaction","detail":"{read_only:false; response_revision:14695; number_of_response:1; }","duration":"119.686148ms","start":"2026-06-06T00:00:52.633141Z","end":"2026-06-06T00:00:52.752828Z","steps":["trace[189178247] 'process raft request' (duration: 119.465283ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:00:53.725865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12709} {"level":"info","ts":"2026-06-06T00:00:53.81976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12709,"took":"90.910018ms","hash":866676239,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":27332608,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-06T00:00:53.819816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":866676239,"revision":12709,"compact-revision":10525} {"level":"info","ts":"2026-06-06T00:01:01.876144Z","caller":"traceutil/trace.go:171","msg":"trace[1005416972] linearizableReadLoop","detail":"{readStateIndex:15369; appliedIndex:15368; }","duration":"147.049837ms","start":"2026-06-06T00:01:01.729071Z","end":"2026-06-06T00:01:01.876121Z","steps":["trace[1005416972] 'read index received' (duration: 145.71029ms)","trace[1005416972] 'applied index is now lower than readState.Index' (duration: 1.338177ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:01:01.876264Z","caller":"traceutil/trace.go:171","msg":"trace[1559430215] transaction","detail":"{read_only:false; response_revision:14859; number_of_response:1; }","duration":"152.889216ms","start":"2026-06-06T00:01:01.723364Z","end":"2026-06-06T00:01:01.876253Z","steps":["trace[1559430215] 'process raft request' (duration: 151.241643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:01:01.876642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.532227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-7659bd74f8-6q2xt\" ","response":"range_response_count:1 size:6895"} {"level":"info","ts":"2026-06-06T00:01:01.8768Z","caller":"traceutil/trace.go:171","msg":"trace[1335583634] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-7659bd74f8-6q2xt; range_end:; response_count:1; response_revision:14859; }","duration":"147.743421ms","start":"2026-06-06T00:01:01.729028Z","end":"2026-06-06T00:01:01.876771Z","steps":["trace[1335583634] 'agreement among raft nodes before linearized reading' (duration: 147.25611ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:02:42.84993Z","caller":"traceutil/trace.go:171","msg":"trace[247191260] transaction","detail":"{read_only:false; response_revision:15959; number_of_response:1; }","duration":"129.028548ms","start":"2026-06-06T00:02:42.720863Z","end":"2026-06-06T00:02:42.849892Z","steps":["trace[247191260] 'process raft request' (duration: 128.794793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:02:43.000787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.410404ms","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-06T00:02:43.000872Z","caller":"traceutil/trace.go:171","msg":"trace[1041053954] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15960; }","duration":"121.501375ms","start":"2026-06-06T00:02:42.879349Z","end":"2026-06-06T00:02:43.000851Z","steps":["trace[1041053954] 'range keys from in-memory index tree' (duration: 121.302172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:02:43.001017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.335106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-06-06T00:02:43.001109Z","caller":"traceutil/trace.go:171","msg":"trace[645027869] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15960; }","duration":"107.473518ms","start":"2026-06-06T00:02:42.893615Z","end":"2026-06-06T00:02:43.001089Z","steps":["trace[645027869] 'range keys from in-memory index tree' (duration: 107.165072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:02:43.000703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.699506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-06-06T00:02:43.001293Z","caller":"traceutil/trace.go:171","msg":"trace[1195509432] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15960; }","duration":"113.329678ms","start":"2026-06-06T00:02:42.88795Z","end":"2026-06-06T00:02:43.00128Z","steps":["trace[1195509432] 'range keys from in-memory index tree' (duration: 112.560503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:02:43.001698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.056727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:80 size:771700"} {"level":"info","ts":"2026-06-06T00:02:43.0018Z","caller":"traceutil/trace.go:171","msg":"trace[52549467] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:80; response_revision:15960; }","duration":"126.205681ms","start":"2026-06-06T00:02:42.875574Z","end":"2026-06-06T00:02:43.00178Z","steps":["trace[52549467] 'range keys from in-memory index tree' (duration: 124.564036ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:02:44.095752Z","caller":"traceutil/trace.go:171","msg":"trace[1576375279] transaction","detail":"{read_only:false; response_revision:15963; number_of_response:1; }","duration":"124.134749ms","start":"2026-06-06T00:02:43.971583Z","end":"2026-06-06T00:02:44.095717Z","steps":["trace[1576375279] 'process raft request' (duration: 123.888093ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:05:53.741993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14703} {"level":"info","ts":"2026-06-06T00:05:53.827276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14703,"took":"80.913625ms","hash":3575443799,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":34365440,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-06T00:05:53.827352Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3575443799,"revision":14703,"compact-revision":12709} {"level":"info","ts":"2026-06-06T00:08:18.961729Z","caller":"traceutil/trace.go:171","msg":"trace[700602106] transaction","detail":"{read_only:false; response_revision:18479; number_of_response:1; }","duration":"146.079357ms","start":"2026-06-06T00:08:18.815603Z","end":"2026-06-06T00:08:18.961683Z","steps":["trace[700602106] 'process raft request' (duration: 110.826047ms)","trace[700602106] 'compare' (duration: 35.079326ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:08:25.150495Z","caller":"traceutil/trace.go:171","msg":"trace[418014698] transaction","detail":"{read_only:false; response_revision:18578; number_of_response:1; }","duration":"133.914368ms","start":"2026-06-06T00:08:25.016439Z","end":"2026-06-06T00:08:25.150353Z","steps":["trace[418014698] 'process raft request' (duration: 133.697544ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:08:25.514049Z","caller":"traceutil/trace.go:171","msg":"trace[732092630] linearizableReadLoop","detail":"{readStateIndex:19203; appliedIndex:19202; }","duration":"164.379832ms","start":"2026-06-06T00:08:25.34963Z","end":"2026-06-06T00:08:25.51401Z","steps":["trace[732092630] 'read index received' (duration: 164.210708ms)","trace[732092630] 'applied index is now lower than readState.Index' (duration: 167.594µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:08:25.514097Z","caller":"traceutil/trace.go:171","msg":"trace[2137316777] transaction","detail":"{read_only:false; response_revision:18579; number_of_response:1; }","duration":"243.411167ms","start":"2026-06-06T00:08:25.270642Z","end":"2026-06-06T00:08:25.514053Z","steps":["trace[2137316777] 'process raft request' (duration: 243.135002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:08:25.514455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.772319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-hzbjf\" ","response":"range_response_count:1 size:22015"} {"level":"info","ts":"2026-06-06T00:08:25.514588Z","caller":"traceutil/trace.go:171","msg":"trace[84849320] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-hzbjf; range_end:; response_count:1; response_revision:18579; }","duration":"164.977474ms","start":"2026-06-06T00:08:25.34958Z","end":"2026-06-06T00:08:25.514558Z","steps":["trace[84849320] 'agreement among raft nodes before linearized reading' (duration: 164.569215ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:08:25.748434Z","caller":"traceutil/trace.go:171","msg":"trace[1541166885] transaction","detail":"{read_only:false; response_revision:18580; number_of_response:1; }","duration":"286.139321ms","start":"2026-06-06T00:08:25.462258Z","end":"2026-06-06T00:08:25.748397Z","steps":["trace[1541166885] 'process raft request' (duration: 263.719082ms)","trace[1541166885] 'compare' (duration: 21.567872ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:08:25.748661Z","caller":"traceutil/trace.go:171","msg":"trace[1986573901] linearizableReadLoop","detail":"{readStateIndex:19204; appliedIndex:19203; }","duration":"226.567594ms","start":"2026-06-06T00:08:25.522056Z","end":"2026-06-06T00:08:25.748623Z","steps":["trace[1986573901] 'read index received' (duration: 203.89521ms)","trace[1986573901] 'applied index is now lower than readState.Index' (duration: 22.670854ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:08:25.749029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.183354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:08:25.749097Z","caller":"traceutil/trace.go:171","msg":"trace[1723240000] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:18581; }","duration":"202.325307ms","start":"2026-06-06T00:08:25.546754Z","end":"2026-06-06T00:08:25.749079Z","steps":["trace[1723240000] 'agreement among raft nodes before linearized reading' (duration: 202.201584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:08:25.749919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.002069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-g8xr7\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-06-06T00:08:25.750084Z","caller":"traceutil/trace.go:171","msg":"trace[1929841060] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-g8xr7; range_end:; response_count:1; response_revision:18581; }","duration":"131.208003ms","start":"2026-06-06T00:08:25.61885Z","end":"2026-06-06T00:08:25.750058Z","steps":["trace[1929841060] 'agreement among raft nodes before linearized reading' (duration: 130.812815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:08:25.751145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.101465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:98 size:958896"} {"level":"info","ts":"2026-06-06T00:08:25.751191Z","caller":"traceutil/trace.go:171","msg":"trace[1645393589] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:98; response_revision:18581; }","duration":"229.207287ms","start":"2026-06-06T00:08:25.521976Z","end":"2026-06-06T00:08:25.751183Z","steps":["trace[1645393589] 'agreement among raft nodes before linearized reading' (duration: 227.278687ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:09:37.942015Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-06T00:09:37.94816Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-06T00:09:37.948279Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-06T00:10:53.748766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17494} {"level":"info","ts":"2026-06-06T00:10:53.875931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17494,"took":"121.992403ms","hash":1252001380,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":35078144,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-06T00:10:53.875997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1252001380,"revision":17494,"compact-revision":14703} {"level":"warn","ts":"2026-06-06T00:14:12.321977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.374786ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-06T00:14:12.322099Z","caller":"traceutil/trace.go:171","msg":"trace[1109762145] linearizableReadLoop","detail":"{readStateIndex:21878; appliedIndex:21877; }","duration":"138.1056ms","start":"2026-06-06T00:14:12.183974Z","end":"2026-06-06T00:14:12.322079Z","steps":["trace[1109762145] 'read index received' (duration: 20.16µs)","trace[1109762145] 'applied index is now lower than readState.Index' (duration: 138.0836ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:14:12.322421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.437187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-06T00:14:12.32246Z","caller":"traceutil/trace.go:171","msg":"trace[284222400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21166; }","duration":"138.525808ms","start":"2026-06-06T00:14:12.183924Z","end":"2026-06-06T00:14:12.32245Z","steps":["trace[284222400] 'agreement among raft nodes before linearized reading' (duration: 138.275613ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:14:12.630228Z","caller":"traceutil/trace.go:171","msg":"trace[1734866904] transaction","detail":"{read_only:false; response_revision:21167; number_of_response:1; }","duration":"301.574838ms","start":"2026-06-06T00:14:12.328612Z","end":"2026-06-06T00:14:12.630187Z","steps":["trace[1734866904] 'process raft request' (duration: 301.310723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:14:12.630596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:14:12.328591Z","time spent":"301.805482ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:14:12.779944Z","caller":"traceutil/trace.go:171","msg":"trace[704942864] transaction","detail":"{read_only:false; response_revision:21168; number_of_response:1; }","duration":"451.186392ms","start":"2026-06-06T00:14:12.328723Z","end":"2026-06-06T00:14:12.779909Z","steps":["trace[704942864] 'process raft request' (duration: 420.21205ms)","trace[704942864] 'compare' (duration: 30.681356ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:14:12.780141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:14:12.328715Z","time spent":"451.319385ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:14:12.780175Z","caller":"traceutil/trace.go:171","msg":"trace[2111257839] linearizableReadLoop","detail":"{readStateIndex:21881; appliedIndex:21878; }","duration":"308.160352ms","start":"2026-06-06T00:14:12.471973Z","end":"2026-06-06T00:14:12.780133Z","steps":["trace[2111257839] 'read index received' (duration: 157.915965ms)","trace[2111257839] 'applied index is now lower than readState.Index' (duration: 150.242617ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:14:12.78025Z","caller":"traceutil/trace.go:171","msg":"trace[10210093] transaction","detail":"{read_only:false; response_revision:21169; number_of_response:1; }","duration":"451.435197ms","start":"2026-06-06T00:14:12.328766Z","end":"2026-06-06T00:14:12.780201Z","steps":["trace[10210093] 'process raft request' (duration: 451.053139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:14:12.780366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.365186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:14:12.780466Z","caller":"traceutil/trace.go:171","msg":"trace[1607337560] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:21169; }","duration":"308.521739ms","start":"2026-06-06T00:14:12.471922Z","end":"2026-06-06T00:14:12.780443Z","steps":["trace[1607337560] 'agreement among raft nodes before linearized reading' (duration: 308.345485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:14:12.780463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:14:12.328761Z","time spent":"451.58574ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:14:12.780517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:14:12.471899Z","time spent":"308.606991ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"info","ts":"2026-06-06T00:14:13.483342Z","caller":"traceutil/trace.go:171","msg":"trace[1927894617] linearizableReadLoop","detail":"{readStateIndex:21884; appliedIndex:21883; }","duration":"171.264536ms","start":"2026-06-06T00:14:13.312044Z","end":"2026-06-06T00:14:13.483309Z","steps":["trace[1927894617] 'read index received' (duration: 166.594902ms)","trace[1927894617] 'applied index is now lower than readState.Index' (duration: 4.667974ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:14:13.483813Z","caller":"traceutil/trace.go:171","msg":"trace[280335610] transaction","detail":"{read_only:false; response_revision:21172; number_of_response:1; }","duration":"255.595228ms","start":"2026-06-06T00:14:13.22819Z","end":"2026-06-06T00:14:13.483785Z","steps":["trace[280335610] 'process raft request' (duration: 250.427943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:14:13.483861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.799368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:14:13.483968Z","caller":"traceutil/trace.go:171","msg":"trace[242525099] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:21172; }","duration":"171.980692ms","start":"2026-06-06T00:14:13.311967Z","end":"2026-06-06T00:14:13.483948Z","steps":["trace[242525099] 'agreement among raft nodes before linearized reading' (duration: 171.772107ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:14:13.66907Z","caller":"traceutil/trace.go:171","msg":"trace[361912401] transaction","detail":"{read_only:false; response_revision:21173; number_of_response:1; }","duration":"180.016206ms","start":"2026-06-06T00:14:13.489016Z","end":"2026-06-06T00:14:13.669032Z","steps":["trace[361912401] 'process raft request' (duration: 127.097055ms)","trace[361912401] 'compare' (duration: 52.743577ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:14:13.928548Z","caller":"traceutil/trace.go:171","msg":"trace[886803422] transaction","detail":"{read_only:false; response_revision:21174; number_of_response:1; }","duration":"203.73526ms","start":"2026-06-06T00:14:13.724769Z","end":"2026-06-06T00:14:13.928504Z","steps":["trace[886803422] 'process raft request' (duration: 130.524785ms)","trace[886803422] 'compare' (duration: 73.025121ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:14:13.933621Z","caller":"traceutil/trace.go:171","msg":"trace[844650304] linearizableReadLoop","detail":"{readStateIndex:21887; appliedIndex:21885; }","duration":"167.939769ms","start":"2026-06-06T00:14:13.765655Z","end":"2026-06-06T00:14:13.933594Z","steps":["trace[844650304] 'read index received' (duration: 89.721772ms)","trace[844650304] 'applied index is now lower than readState.Index' (duration: 78.216897ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:14:13.93377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.093162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:14:13.933861Z","caller":"traceutil/trace.go:171","msg":"trace[960801789] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:21175; }","duration":"168.162323ms","start":"2026-06-06T00:14:13.76564Z","end":"2026-06-06T00:14:13.933803Z","steps":["trace[960801789] 'agreement among raft nodes before linearized reading' (duration: 168.060901ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:14:13.933691Z","caller":"traceutil/trace.go:171","msg":"trace[1887938815] transaction","detail":"{read_only:false; response_revision:21175; number_of_response:1; }","duration":"207.445215ms","start":"2026-06-06T00:14:13.726204Z","end":"2026-06-06T00:14:13.933649Z","steps":["trace[1887938815] 'process raft request' (duration: 207.259201ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:15:53.75627Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19854} {"level":"info","ts":"2026-06-06T00:15:53.877059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19854,"took":"116.234903ms","hash":2109656101,"current-db-size-bytes":58015744,"current-db-size":"58 MB","current-db-size-in-use-bytes":30257152,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-06T00:15:53.877167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2109656101,"revision":19854,"compact-revision":17494} {"level":"warn","ts":"2026-06-06T00:18:16.045439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.999066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:18:16.045528Z","caller":"traceutil/trace.go:171","msg":"trace[1347268845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23659; }","duration":"235.106529ms","start":"2026-06-06T00:18:15.810404Z","end":"2026-06-06T00:18:16.045511Z","steps":["trace[1347268845] 'range keys from in-memory index tree' (duration: 234.840913ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:19:56.874157Z","caller":"traceutil/trace.go:171","msg":"trace[1921975263] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"148.800797ms","start":"2026-06-06T00:19:56.725286Z","end":"2026-06-06T00:19:56.874087Z","steps":["trace[1921975263] 'process raft request' (duration: 117.485788ms)","trace[1921975263] 'compare' (duration: 31.096715ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:20:53.763342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22042} {"level":"info","ts":"2026-06-06T00:20:53.874929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22042,"took":"106.060284ms","hash":2508050321,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":43499520,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-06-06T00:20:53.874987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2508050321,"revision":22042,"compact-revision":19854} {"level":"info","ts":"2026-06-06T00:21:59.701209Z","caller":"traceutil/trace.go:171","msg":"trace[797787560] transaction","detail":"{read_only:false; response_revision:25910; number_of_response:1; }","duration":"123.989531ms","start":"2026-06-06T00:21:59.577187Z","end":"2026-06-06T00:21:59.701177Z","steps":["trace[797787560] 'process raft request' (duration: 123.788807ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:21:59.703907Z","caller":"traceutil/trace.go:171","msg":"trace[444169558] transaction","detail":"{read_only:false; response_revision:25912; number_of_response:1; }","duration":"123.655004ms","start":"2026-06-06T00:21:59.580088Z","end":"2026-06-06T00:21:59.703743Z","steps":["trace[444169558] 'process raft request' (duration: 123.370898ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:21:59.703923Z","caller":"traceutil/trace.go:171","msg":"trace[195689347] transaction","detail":"{read_only:false; response_revision:25911; number_of_response:1; }","duration":"125.48403ms","start":"2026-06-06T00:21:59.578369Z","end":"2026-06-06T00:21:59.703853Z","steps":["trace[195689347] 'process raft request' (duration: 124.899568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:05.207306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.259646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:05.207401Z","caller":"traceutil/trace.go:171","msg":"trace[691852919] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:25951; }","duration":"197.419288ms","start":"2026-06-06T00:22:05.00996Z","end":"2026-06-06T00:22:05.207379Z","steps":["trace[691852919] 'count revisions from in-memory index tree' (duration: 197.165484ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:05.496332Z","caller":"traceutil/trace.go:171","msg":"trace[1090133649] linearizableReadLoop","detail":"{readStateIndex:26799; appliedIndex:26798; }","duration":"173.063663ms","start":"2026-06-06T00:22:05.323239Z","end":"2026-06-06T00:22:05.496302Z","steps":["trace[1090133649] 'read index received' (duration: 172.92111ms)","trace[1090133649] 'applied index is now lower than readState.Index' (duration: 140.923µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:05.496492Z","caller":"traceutil/trace.go:171","msg":"trace[328687178] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"194.763185ms","start":"2026-06-06T00:22:05.301713Z","end":"2026-06-06T00:22:05.496476Z","steps":["trace[328687178] 'process raft request' (duration: 194.400138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:05.496816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.554343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T00:22:05.496909Z","caller":"traceutil/trace.go:171","msg":"trace[1136885589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25952; }","duration":"173.706515ms","start":"2026-06-06T00:22:05.323184Z","end":"2026-06-06T00:22:05.49689Z","steps":["trace[1136885589] 'agreement among raft nodes before linearized reading' (duration: 173.404469ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:05.56719Z","caller":"traceutil/trace.go:171","msg":"trace[1195475412] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"101.567492ms","start":"2026-06-06T00:22:05.465582Z","end":"2026-06-06T00:22:05.567149Z","steps":["trace[1195475412] 'process raft request' (duration: 101.47732ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:05.567195Z","caller":"traceutil/trace.go:171","msg":"trace[267028801] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"188.050188ms","start":"2026-06-06T00:22:05.379084Z","end":"2026-06-06T00:22:05.567134Z","steps":["trace[267028801] 'process raft request' (duration: 151.819268ms)","trace[267028801] 'compare' (duration: 35.997145ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:05.567479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.305823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:05.567588Z","caller":"traceutil/trace.go:171","msg":"trace[1917492663] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25955; }","duration":"212.497847ms","start":"2026-06-06T00:22:05.355067Z","end":"2026-06-06T00:22:05.567565Z","steps":["trace[1917492663] 'agreement among raft nodes before linearized reading' (duration: 212.076348ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:09.677572Z","caller":"traceutil/trace.go:171","msg":"trace[946570144] linearizableReadLoop","detail":"{readStateIndex:26827; appliedIndex:26826; }","duration":"123.92947ms","start":"2026-06-06T00:22:09.553602Z","end":"2026-06-06T00:22:09.677531Z","steps":["trace[946570144] 'read index received' (duration: 123.699475ms)","trace[946570144] 'applied index is now lower than readState.Index' (duration: 228.375µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:09.677646Z","caller":"traceutil/trace.go:171","msg":"trace[77596491] transaction","detail":"{read_only:false; response_revision:25979; number_of_response:1; }","duration":"166.831696ms","start":"2026-06-06T00:22:09.510781Z","end":"2026-06-06T00:22:09.677612Z","steps":["trace[77596491] 'process raft request' (duration: 166.493339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:09.677933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.292738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:09.678013Z","caller":"traceutil/trace.go:171","msg":"trace[1962038632] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25979; }","duration":"124.496531ms","start":"2026-06-06T00:22:09.553498Z","end":"2026-06-06T00:22:09.677994Z","steps":["trace[1962038632] 'agreement among raft nodes before linearized reading' (duration: 124.209015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:09.689607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.3705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T00:22:09.689676Z","caller":"traceutil/trace.go:171","msg":"trace[7569285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25981; }","duration":"105.493013ms","start":"2026-06-06T00:22:09.584168Z","end":"2026-06-06T00:22:09.689661Z","steps":["trace[7569285] 'agreement among raft nodes before linearized reading' (duration: 105.275248ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:09.689967Z","caller":"traceutil/trace.go:171","msg":"trace[531590693] transaction","detail":"{read_only:false; response_revision:25980; number_of_response:1; }","duration":"107.204948ms","start":"2026-06-06T00:22:09.582749Z","end":"2026-06-06T00:22:09.689954Z","steps":["trace[531590693] 'process raft request' (duration: 106.509664ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:09.690123Z","caller":"traceutil/trace.go:171","msg":"trace[453606157] transaction","detail":"{read_only:false; response_revision:25981; number_of_response:1; }","duration":"104.970972ms","start":"2026-06-06T00:22:09.585142Z","end":"2026-06-06T00:22:09.690113Z","steps":["trace[453606157] 'process raft request' (duration: 104.248487ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:12.753724Z","caller":"traceutil/trace.go:171","msg":"trace[1673406823] linearizableReadLoop","detail":"{readStateIndex:26854; appliedIndex:26853; }","duration":"167.805186ms","start":"2026-06-06T00:22:12.585897Z","end":"2026-06-06T00:22:12.753702Z","steps":["trace[1673406823] 'read index received' (duration: 167.706884ms)","trace[1673406823] 'applied index is now lower than readState.Index' (duration: 96.762µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:12.75377Z","caller":"traceutil/trace.go:171","msg":"trace[1335151667] transaction","detail":"{read_only:false; response_revision:26005; number_of_response:1; }","duration":"238.164511ms","start":"2026-06-06T00:22:12.515574Z","end":"2026-06-06T00:22:12.753739Z","steps":["trace[1335151667] 'process raft request' (duration: 237.968267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:12.753897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.981939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T00:22:12.753926Z","caller":"traceutil/trace.go:171","msg":"trace[708530563] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:26005; }","duration":"168.116022ms","start":"2026-06-06T00:22:12.5858Z","end":"2026-06-06T00:22:12.753916Z","steps":["trace[708530563] 'agreement among raft nodes before linearized reading' (duration: 167.983289ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:12.788089Z","caller":"traceutil/trace.go:171","msg":"trace[936614168] transaction","detail":"{read_only:false; response_revision:26007; number_of_response:1; }","duration":"136.965196ms","start":"2026-06-06T00:22:12.651093Z","end":"2026-06-06T00:22:12.788058Z","steps":["trace[936614168] 'process raft request' (duration: 136.917245ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:12.788317Z","caller":"traceutil/trace.go:171","msg":"trace[1351168281] transaction","detail":"{read_only:false; response_revision:26006; number_of_response:1; }","duration":"137.326372ms","start":"2026-06-06T00:22:12.650969Z","end":"2026-06-06T00:22:12.788296Z","steps":["trace[1351168281] 'process raft request' (duration: 136.833782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:14.114785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.256745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:22:14.115047Z","caller":"traceutil/trace.go:171","msg":"trace[1351437643] transaction","detail":"{read_only:false; response_revision:26015; number_of_response:1; }","duration":"224.370999ms","start":"2026-06-06T00:22:13.890657Z","end":"2026-06-06T00:22:14.115028Z","steps":["trace[1351437643] 'process raft request' (duration: 224.319548ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:14.115404Z","caller":"traceutil/trace.go:171","msg":"trace[1922129782] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"329.390963ms","start":"2026-06-06T00:22:13.785997Z","end":"2026-06-06T00:22:14.115388Z","steps":["trace[1922129782] 'process raft request' (duration: 132.439524ms)","trace[1922129782] 'compare' (duration: 195.892357ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:14.115479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:13.785974Z","time spent":"329.468004ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:14.11591Z","caller":"traceutil/trace.go:171","msg":"trace[113329819] linearizableReadLoop","detail":"{readStateIndex:26862; appliedIndex:26861; }","duration":"329.844973ms","start":"2026-06-06T00:22:13.786049Z","end":"2026-06-06T00:22:14.115894Z","steps":["trace[113329819] 'read index received' (duration: 132.409494ms)","trace[113329819] 'applied index is now lower than readState.Index' (duration: 197.433199ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:14.116011Z","caller":"traceutil/trace.go:171","msg":"trace[512734059] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"234.415765ms","start":"2026-06-06T00:22:13.881586Z","end":"2026-06-06T00:22:14.116002Z","steps":["trace[512734059] 'process raft request' (duration: 233.333873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:14.116356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.302152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-06T00:22:14.116399Z","caller":"traceutil/trace.go:171","msg":"trace[133418321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26015; }","duration":"330.400274ms","start":"2026-06-06T00:22:13.785986Z","end":"2026-06-06T00:22:14.116386Z","steps":["trace[133418321] 'agreement among raft nodes before linearized reading' (duration: 330.262121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:14.11643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:13.785949Z","time spent":"330.474155ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-06T00:22:14.116461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.20164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-06T00:22:14.116544Z","caller":"traceutil/trace.go:171","msg":"trace[273342488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26015; }","duration":"330.288701ms","start":"2026-06-06T00:22:13.786233Z","end":"2026-06-06T00:22:14.116521Z","steps":["trace[273342488] 'agreement among raft nodes before linearized reading' (duration: 330.082647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:14.116602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:13.78616Z","time spent":"330.432915ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-06T00:22:14.116594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.121505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T00:22:14.116684Z","caller":"traceutil/trace.go:171","msg":"trace[1247032232] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:26015; }","duration":"324.255087ms","start":"2026-06-06T00:22:13.792406Z","end":"2026-06-06T00:22:14.116661Z","steps":["trace[1247032232] 'agreement among raft nodes before linearized reading' (duration: 324.049243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:14.116738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:13.792381Z","time spent":"324.344559ms","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":37,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-06-06T00:22:14.278574Z","caller":"traceutil/trace.go:171","msg":"trace[1544712696] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"157.32816ms","start":"2026-06-06T00:22:14.121186Z","end":"2026-06-06T00:22:14.278514Z","steps":["trace[1544712696] 'process raft request' (duration: 156.892082ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:14.292646Z","caller":"traceutil/trace.go:171","msg":"trace[192131171] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"171.359307ms","start":"2026-06-06T00:22:14.12125Z","end":"2026-06-06T00:22:14.292609Z","steps":["trace[192131171] 'process raft request' (duration: 171.135133ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:14.88605Z","caller":"traceutil/trace.go:171","msg":"trace[819644356] transaction","detail":"{read_only:false; response_revision:26018; number_of_response:1; }","duration":"117.704103ms","start":"2026-06-06T00:22:14.768324Z","end":"2026-06-06T00:22:14.886028Z","steps":["trace[819644356] 'process raft request' (duration: 117.56429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:15.06602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.813711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:22:15.066278Z","caller":"traceutil/trace.go:171","msg":"trace[118290684] transaction","detail":"{read_only:false; response_revision:26019; number_of_response:1; }","duration":"270.98085ms","start":"2026-06-06T00:22:14.795269Z","end":"2026-06-06T00:22:15.06625Z","steps":["trace[118290684] 'process raft request' (duration: 138.828262ms)","trace[118290684] 'compare' (duration: 131.554476ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:15.066305Z","caller":"traceutil/trace.go:171","msg":"trace[1751202227] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"270.715355ms","start":"2026-06-06T00:22:14.795564Z","end":"2026-06-06T00:22:15.066279Z","steps":["trace[1751202227] 'process raft request' (duration: 270.597803ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:15.066545Z","caller":"traceutil/trace.go:171","msg":"trace[226311251] linearizableReadLoop","detail":"{readStateIndex:26869; appliedIndex:26867; }","duration":"260.461016ms","start":"2026-06-06T00:22:14.806076Z","end":"2026-06-06T00:22:15.066537Z","steps":["trace[226311251] 'read index received' (duration: 80.262029ms)","trace[226311251] 'applied index is now lower than readState.Index' (duration: 180.198127ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:15.066907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.659036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-06-06T00:22:15.066864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.739452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:15.066947Z","caller":"traceutil/trace.go:171","msg":"trace[1226199476] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26021; }","duration":"176.742598ms","start":"2026-06-06T00:22:14.890197Z","end":"2026-06-06T00:22:15.066939Z","steps":["trace[1226199476] 'agreement among raft nodes before linearized reading' (duration: 176.604485ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:15.066945Z","caller":"traceutil/trace.go:171","msg":"trace[1195056450] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"186.454296ms","start":"2026-06-06T00:22:14.880476Z","end":"2026-06-06T00:22:15.06693Z","steps":["trace[1195056450] 'process raft request' (duration: 185.750801ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:15.066997Z","caller":"traceutil/trace.go:171","msg":"trace[1640365404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26021; }","duration":"260.932367ms","start":"2026-06-06T00:22:14.806029Z","end":"2026-06-06T00:22:15.066961Z","steps":["trace[1640365404] 'agreement among raft nodes before linearized reading' (duration: 260.62737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:15.067238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.93537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T00:22:15.067342Z","caller":"traceutil/trace.go:171","msg":"trace[642110448] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:26021; }","duration":"124.109154ms","start":"2026-06-06T00:22:14.943209Z","end":"2026-06-06T00:22:15.067318Z","steps":["trace[642110448] 'agreement among raft nodes before linearized reading' (duration: 123.862559ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:15.942796Z","caller":"traceutil/trace.go:171","msg":"trace[1186293287] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"269.113513ms","start":"2026-06-06T00:22:15.673646Z","end":"2026-06-06T00:22:15.942759Z","steps":["trace[1186293287] 'process raft request' (duration: 268.900369ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:16.090477Z","caller":"traceutil/trace.go:171","msg":"trace[398632703] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"372.726178ms","start":"2026-06-06T00:22:15.717724Z","end":"2026-06-06T00:22:16.09045Z","steps":["trace[398632703] 'process raft request' (duration: 372.484223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:16.090676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:15.717683Z","time spent":"372.883351ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:16.090946Z","caller":"traceutil/trace.go:171","msg":"trace[322725872] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"372.919471ms","start":"2026-06-06T00:22:15.717994Z","end":"2026-06-06T00:22:16.090914Z","steps":["trace[322725872] 'process raft request' (duration: 372.407251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:16.091155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:15.717968Z","time spent":"373.072895ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:16.092074Z","caller":"traceutil/trace.go:171","msg":"trace[888662599] linearizableReadLoop","detail":"{readStateIndex:26876; appliedIndex:26872; }","duration":"327.663617ms","start":"2026-06-06T00:22:15.764393Z","end":"2026-06-06T00:22:16.092057Z","steps":["trace[888662599] 'read index received' (duration: 178.142876ms)","trace[888662599] 'applied index is now lower than readState.Index' (duration: 149.519681ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:16.092198Z","caller":"traceutil/trace.go:171","msg":"trace[8663216] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"304.602108ms","start":"2026-06-06T00:22:15.787586Z","end":"2026-06-06T00:22:16.092188Z","steps":["trace[8663216] 'process raft request' (duration: 304.423773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:16.092286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:15.787548Z","time spent":"304.6927ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:22:16.092301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.880463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:16.092275Z","caller":"traceutil/trace.go:171","msg":"trace[1966698810] transaction","detail":"{read_only:false; response_revision:26027; number_of_response:1; }","duration":"373.665267ms","start":"2026-06-06T00:22:15.718551Z","end":"2026-06-06T00:22:16.092217Z","steps":["trace[1966698810] 'process raft request' (duration: 373.35498ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:16.092351Z","caller":"traceutil/trace.go:171","msg":"trace[1343402909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26028; }","duration":"282.973836ms","start":"2026-06-06T00:22:15.809364Z","end":"2026-06-06T00:22:16.092338Z","steps":["trace[1343402909] 'agreement among raft nodes before linearized reading' (duration: 282.858623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:16.092549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:15.718545Z","time spent":"373.858691ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:22:16.092542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.134118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T00:22:16.092624Z","caller":"traceutil/trace.go:171","msg":"trace[1195201682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26028; }","duration":"328.275811ms","start":"2026-06-06T00:22:15.764328Z","end":"2026-06-06T00:22:16.092604Z","steps":["trace[1195201682] 'agreement among raft nodes before linearized reading' (duration: 328.023546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:16.092685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:15.764308Z","time spent":"328.368132ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-06T00:22:16.302528Z","caller":"traceutil/trace.go:171","msg":"trace[1023955896] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"205.109378ms","start":"2026-06-06T00:22:16.097375Z","end":"2026-06-06T00:22:16.302485Z","steps":["trace[1023955896] 'process raft request' (duration: 141.084571ms)","trace[1023955896] 'compare' (duration: 63.608307ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:16.302615Z","caller":"traceutil/trace.go:171","msg":"trace[69855641] transaction","detail":"{read_only:false; response_revision:26030; number_of_response:1; }","duration":"180.507955ms","start":"2026-06-06T00:22:16.122097Z","end":"2026-06-06T00:22:16.302605Z","steps":["trace[69855641] 'process raft request' (duration: 180.306841ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:16.302546Z","caller":"traceutil/trace.go:171","msg":"trace[756599966] transaction","detail":"{read_only:false; response_revision:26031; number_of_response:1; }","duration":"180.116196ms","start":"2026-06-06T00:22:16.122402Z","end":"2026-06-06T00:22:16.302518Z","steps":["trace[756599966] 'process raft request' (duration: 180.059835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:16.302791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.333031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-06T00:22:16.302838Z","caller":"traceutil/trace.go:171","msg":"trace[1285314917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26031; }","duration":"181.433274ms","start":"2026-06-06T00:22:16.121392Z","end":"2026-06-06T00:22:16.302825Z","steps":["trace[1285314917] 'agreement among raft nodes before linearized reading' (duration: 181.219529ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:16.302551Z","caller":"traceutil/trace.go:171","msg":"trace[2090452944] linearizableReadLoop","detail":"{readStateIndex:26878; appliedIndex:26877; }","duration":"181.084977ms","start":"2026-06-06T00:22:16.121436Z","end":"2026-06-06T00:22:16.302521Z","steps":["trace[2090452944] 'read index received' (duration: 117.10909ms)","trace[2090452944] 'applied index is now lower than readState.Index' (duration: 63.972906ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:16.303048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.467653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:16.303085Z","caller":"traceutil/trace.go:171","msg":"trace[1691621715] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26031; }","duration":"104.567225ms","start":"2026-06-06T00:22:16.198508Z","end":"2026-06-06T00:22:16.303075Z","steps":["trace[1691621715] 'agreement among raft nodes before linearized reading' (duration: 104.508813ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:16.585127Z","caller":"traceutil/trace.go:171","msg":"trace[1178992340] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"276.715395ms","start":"2026-06-06T00:22:16.308376Z","end":"2026-06-06T00:22:16.585091Z","steps":["trace[1178992340] 'process raft request' (duration: 222.679283ms)","trace[1178992340] 'compare' (duration: 53.864848ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:16.626329Z","caller":"traceutil/trace.go:171","msg":"trace[1967648652] linearizableReadLoop","detail":"{readStateIndex:26883; appliedIndex:26880; }","duration":"252.498051ms","start":"2026-06-06T00:22:16.373804Z","end":"2026-06-06T00:22:16.626302Z","steps":["trace[1967648652] 'read index received' (duration: 157.230697ms)","trace[1967648652] 'applied index is now lower than readState.Index' (duration: 95.266264ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:16.626722Z","caller":"traceutil/trace.go:171","msg":"trace[582911291] transaction","detail":"{read_only:false; response_revision:26033; number_of_response:1; }","duration":"317.815184ms","start":"2026-06-06T00:22:16.308886Z","end":"2026-06-06T00:22:16.626701Z","steps":["trace[582911291] 'process raft request' (duration: 317.228412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:16.627124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.99757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T00:22:16.627187Z","caller":"traceutil/trace.go:171","msg":"trace[608795754] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26034; }","duration":"252.089231ms","start":"2026-06-06T00:22:16.375084Z","end":"2026-06-06T00:22:16.627173Z","steps":["trace[608795754] 'agreement among raft nodes before linearized reading' (duration: 251.974389ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:16.627452Z","caller":"traceutil/trace.go:171","msg":"trace[1024011172] transaction","detail":"{read_only:false; response_revision:26034; number_of_response:1; }","duration":"318.143362ms","start":"2026-06-06T00:22:16.309292Z","end":"2026-06-06T00:22:16.627436Z","steps":["trace[1024011172] 'process raft request' (duration: 316.958247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:16.627557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:16.309283Z","time spent":"318.232233ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:22:16.62778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.966901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:16.627865Z","caller":"traceutil/trace.go:171","msg":"trace[1859058468] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:26034; }","duration":"254.105404ms","start":"2026-06-06T00:22:16.373746Z","end":"2026-06-06T00:22:16.627851Z","steps":["trace[1859058468] 'agreement among raft nodes before linearized reading' (duration: 253.954621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:16.626876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:16.308864Z","time spent":"317.907325ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:17.068779Z","caller":"traceutil/trace.go:171","msg":"trace[1710726795] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"141.371703ms","start":"2026-06-06T00:22:16.927388Z","end":"2026-06-06T00:22:17.06876Z","steps":["trace[1710726795] 'process raft request' (duration: 141.057427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:17.354582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.20385ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-06T00:22:17.354984Z","caller":"traceutil/trace.go:171","msg":"trace[209096204] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"281.726806ms","start":"2026-06-06T00:22:17.073228Z","end":"2026-06-06T00:22:17.354955Z","steps":["trace[209096204] 'process raft request' (duration: 281.482841ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:17.355288Z","caller":"traceutil/trace.go:171","msg":"trace[689715690] linearizableReadLoop","detail":"{readStateIndex:26887; appliedIndex:26884; }","duration":"281.565334ms","start":"2026-06-06T00:22:17.073666Z","end":"2026-06-06T00:22:17.355231Z","steps":["trace[689715690] 'read index received' (duration: 65.71326ms)","trace[689715690] 'applied index is now lower than readState.Index' (duration: 215.842923ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:17.35533Z","caller":"traceutil/trace.go:171","msg":"trace[599095858] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"281.89245ms","start":"2026-06-06T00:22:17.073404Z","end":"2026-06-06T00:22:17.355296Z","steps":["trace[599095858] 'process raft request' (duration: 281.495062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:17.355489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.787618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:17.355599Z","caller":"traceutil/trace.go:171","msg":"trace[1444644463] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26037; }","duration":"281.917001ms","start":"2026-06-06T00:22:17.073655Z","end":"2026-06-06T00:22:17.355572Z","steps":["trace[1444644463] 'agreement among raft nodes before linearized reading' (duration: 281.747857ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:17.656421Z","caller":"traceutil/trace.go:171","msg":"trace[121184229] linearizableReadLoop","detail":"{readStateIndex:26888; appliedIndex:26887; }","duration":"293.835884ms","start":"2026-06-06T00:22:17.362545Z","end":"2026-06-06T00:22:17.656381Z","steps":["trace[121184229] 'read index received' (duration: 293.59649ms)","trace[121184229] 'applied index is now lower than readState.Index' (duration: 237.304µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:17.656441Z","caller":"traceutil/trace.go:171","msg":"trace[1672910582] transaction","detail":"{read_only:false; response_revision:26038; number_of_response:1; }","duration":"298.728935ms","start":"2026-06-06T00:22:17.357678Z","end":"2026-06-06T00:22:17.656407Z","steps":["trace[1672910582] 'process raft request' (duration: 298.458579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:17.65663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.044938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:17.656713Z","caller":"traceutil/trace.go:171","msg":"trace[760539495] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26038; }","duration":"294.204081ms","start":"2026-06-06T00:22:17.362485Z","end":"2026-06-06T00:22:17.656689Z","steps":["trace[760539495] 'agreement among raft nodes before linearized reading' (duration: 293.968587ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:18.216056Z","caller":"traceutil/trace.go:171","msg":"trace[1055519026] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"119.007548ms","start":"2026-06-06T00:22:18.09702Z","end":"2026-06-06T00:22:18.216028Z","steps":["trace[1055519026] 'process raft request' (duration: 118.746713ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:18.217735Z","caller":"traceutil/trace.go:171","msg":"trace[1535791199] transaction","detail":"{read_only:false; response_revision:26041; number_of_response:1; }","duration":"120.277704ms","start":"2026-06-06T00:22:18.097432Z","end":"2026-06-06T00:22:18.21771Z","steps":["trace[1535791199] 'process raft request' (duration: 119.635661ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:18.217704Z","caller":"traceutil/trace.go:171","msg":"trace[1138792387] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"119.848865ms","start":"2026-06-06T00:22:18.097798Z","end":"2026-06-06T00:22:18.217646Z","steps":["trace[1138792387] 'process raft request' (duration: 119.438306ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:18.217718Z","caller":"traceutil/trace.go:171","msg":"trace[2112599449] transaction","detail":"{read_only:false; response_revision:26043; number_of_response:1; }","duration":"119.024397ms","start":"2026-06-06T00:22:18.098655Z","end":"2026-06-06T00:22:18.217679Z","steps":["trace[2112599449] 'process raft request' (duration: 118.619249ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:18.574867Z","caller":"traceutil/trace.go:171","msg":"trace[1311111339] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"266.278672ms","start":"2026-06-06T00:22:18.308544Z","end":"2026-06-06T00:22:18.574823Z","steps":["trace[1311111339] 'process raft request' (duration: 265.971065ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:18.657459Z","caller":"traceutil/trace.go:171","msg":"trace[965433368] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"348.606901ms","start":"2026-06-06T00:22:18.308822Z","end":"2026-06-06T00:22:18.657429Z","steps":["trace[965433368] 'process raft request' (duration: 303.378419ms)","trace[965433368] 'compare' (duration: 45.057399ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:18.657453Z","caller":"traceutil/trace.go:171","msg":"trace[1790953567] linearizableReadLoop","detail":"{readStateIndex:26895; appliedIndex:26893; }","duration":"347.757014ms","start":"2026-06-06T00:22:18.309658Z","end":"2026-06-06T00:22:18.657415Z","steps":["trace[1790953567] 'read index received' (duration: 264.805832ms)","trace[1790953567] 'applied index is now lower than readState.Index' (duration: 82.949672ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:18.657614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:18.308796Z","time spent":"348.728353ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:22:18.657622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.948638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T00:22:18.658572Z","caller":"traceutil/trace.go:171","msg":"trace[634688835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26045; }","duration":"348.923009ms","start":"2026-06-06T00:22:18.309618Z","end":"2026-06-06T00:22:18.658541Z","steps":["trace[634688835] 'agreement among raft nodes before linearized reading' (duration: 347.886037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:18.658677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:18.309609Z","time spent":"349.02994ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-06T00:22:21.462147Z","caller":"traceutil/trace.go:171","msg":"trace[1494867917] transaction","detail":"{read_only:false; response_revision:26065; number_of_response:1; }","duration":"372.682552ms","start":"2026-06-06T00:22:21.08942Z","end":"2026-06-06T00:22:21.462103Z","steps":["trace[1494867917] 'process raft request' (duration: 372.08918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:21.462497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:21.089397Z","time spent":"372.972789ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:21.463606Z","caller":"traceutil/trace.go:171","msg":"trace[1033295131] linearizableReadLoop","detail":"{readStateIndex:26915; appliedIndex:26915; }","duration":"180.89001ms","start":"2026-06-06T00:22:21.282671Z","end":"2026-06-06T00:22:21.463561Z","steps":["trace[1033295131] 'read index received' (duration: 180.8711ms)","trace[1033295131] 'applied index is now lower than readState.Index' (duration: 15.12µs)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:21.463865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.144625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T00:22:21.463937Z","caller":"traceutil/trace.go:171","msg":"trace[787335709] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:26065; }","duration":"181.292478ms","start":"2026-06-06T00:22:21.282623Z","end":"2026-06-06T00:22:21.463915Z","steps":["trace[787335709] 'agreement among raft nodes before linearized reading' (duration: 181.078744ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:21.686395Z","caller":"traceutil/trace.go:171","msg":"trace[1189209398] transaction","detail":"{read_only:false; response_revision:26067; number_of_response:1; }","duration":"315.881353ms","start":"2026-06-06T00:22:21.370471Z","end":"2026-06-06T00:22:21.686352Z","steps":["trace[1189209398] 'process raft request' (duration: 315.774631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:21.686657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:21.370442Z","time spent":"316.079378ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:21.686907Z","caller":"traceutil/trace.go:171","msg":"trace[406191835] transaction","detail":"{read_only:false; response_revision:26066; number_of_response:1; }","duration":"317.11255ms","start":"2026-06-06T00:22:21.369732Z","end":"2026-06-06T00:22:21.686845Z","steps":["trace[406191835] 'process raft request' (duration: 312.914454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:21.687195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:21.36971Z","time spent":"317.309084ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:34.401093Z","caller":"traceutil/trace.go:171","msg":"trace[1521523205] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"113.186629ms","start":"2026-06-06T00:22:34.287879Z","end":"2026-06-06T00:22:34.401066Z","steps":["trace[1521523205] 'process raft request' (duration: 66.546158ms)","trace[1521523205] 'compare' (duration: 46.545279ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:34.40118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.57107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11ff168a-9f5c-44b7-b104-47a3026ae73a\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-06T00:22:34.401259Z","caller":"traceutil/trace.go:171","msg":"trace[1649459132] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11ff168a-9f5c-44b7-b104-47a3026ae73a; range_end:; response_count:1; response_revision:26211; }","duration":"196.716293ms","start":"2026-06-06T00:22:34.204523Z","end":"2026-06-06T00:22:34.401239Z","steps":["trace[1649459132] 'range keys from in-memory index tree' (duration: 196.384236ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:34.673831Z","caller":"traceutil/trace.go:171","msg":"trace[1357254719] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"385.892512ms","start":"2026-06-06T00:22:34.287891Z","end":"2026-06-06T00:22:34.673783Z","steps":["trace[1357254719] 'process raft request' (duration: 294.092509ms)","trace[1357254719] 'compare' (duration: 91.62321ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:34.67401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:34.287857Z","time spent":"386.068676ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:34.674373Z","caller":"traceutil/trace.go:171","msg":"trace[1089958091] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"377.663075ms","start":"2026-06-06T00:22:34.2967Z","end":"2026-06-06T00:22:34.674363Z","steps":["trace[1089958091] 'process raft request' (duration: 377.477551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:34.674426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:34.296656Z","time spent":"377.742556ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:34.674396Z","caller":"traceutil/trace.go:171","msg":"trace[679023891] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"384.062094ms","start":"2026-06-06T00:22:34.290279Z","end":"2026-06-06T00:22:34.674341Z","steps":["trace[679023891] 'process raft request' (duration: 383.468872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:34.674687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:34.290265Z","time spent":"384.30337ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:22:35.064751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.426421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:22:35.064883Z","caller":"traceutil/trace.go:171","msg":"trace[1446401868] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"309.771509ms","start":"2026-06-06T00:22:34.755071Z","end":"2026-06-06T00:22:35.064843Z","steps":["trace[1446401868] 'process raft request' (duration: 128.158354ms)","trace[1446401868] 'compare' (duration: 181.240957ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:35.064952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:34.755043Z","time spent":"309.881202ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:35.122727Z","caller":"traceutil/trace.go:171","msg":"trace[1333355578] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"367.171921ms","start":"2026-06-06T00:22:34.75553Z","end":"2026-06-06T00:22:35.122702Z","steps":["trace[1333355578] 'process raft request' (duration: 367.049818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:35.122835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.996706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-06T00:22:35.122859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:34.755516Z","time spent":"367.278602ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:35.122886Z","caller":"traceutil/trace.go:171","msg":"trace[640164004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26221; }","duration":"313.105298ms","start":"2026-06-06T00:22:34.809768Z","end":"2026-06-06T00:22:35.122873Z","steps":["trace[640164004] 'agreement among raft nodes before linearized reading' (duration: 313.008226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:35.122932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:34.80975Z","time spent":"313.174789ms","remote":"127.0.0.1:53988","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-06T00:22:35.122971Z","caller":"traceutil/trace.go:171","msg":"trace[538879238] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"367.514588ms","start":"2026-06-06T00:22:34.755449Z","end":"2026-06-06T00:22:35.122964Z","steps":["trace[538879238] 'process raft request' (duration: 366.990297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:35.122998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:34.755416Z","time spent":"367.566488ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:22:35.122716Z","caller":"traceutil/trace.go:171","msg":"trace[1142648340] linearizableReadLoop","detail":"{readStateIndex:27075; appliedIndex:27071; }","duration":"312.831822ms","start":"2026-06-06T00:22:34.809841Z","end":"2026-06-06T00:22:35.122673Z","steps":["trace[1142648340] 'read index received' (duration: 73.466798ms)","trace[1142648340] 'applied index is now lower than readState.Index' (duration: 239.363934ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:35.123091Z","caller":"traceutil/trace.go:171","msg":"trace[1543656337] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"367.423895ms","start":"2026-06-06T00:22:34.75566Z","end":"2026-06-06T00:22:35.123084Z","steps":["trace[1543656337] 'process raft request' (duration: 366.969986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:35.123121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:34.755639Z","time spent":"367.469377ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:22:35.342201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.340399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:22:35.342332Z","caller":"traceutil/trace.go:171","msg":"trace[926653317] linearizableReadLoop","detail":"{readStateIndex:27076; appliedIndex:27075; }","duration":"219.256402ms","start":"2026-06-06T00:22:35.123055Z","end":"2026-06-06T00:22:35.342311Z","steps":["trace[926653317] 'read index received' (duration: 86.611587ms)","trace[926653317] 'applied index is now lower than readState.Index' (duration: 132.643675ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:35.342344Z","caller":"traceutil/trace.go:171","msg":"trace[1584496738] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"238.601977ms","start":"2026-06-06T00:22:35.103718Z","end":"2026-06-06T00:22:35.34232Z","steps":["trace[1584496738] 'process raft request' (duration: 106.014223ms)","trace[1584496738] 'compare' (duration: 132.197196ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:35.342551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.348958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11ff168a-9f5c-44b7-b104-47a3026ae73a\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-06T00:22:35.342591Z","caller":"traceutil/trace.go:171","msg":"trace[35761728] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11ff168a-9f5c-44b7-b104-47a3026ae73a; range_end:; response_count:1; response_revision:26222; }","duration":"253.432639ms","start":"2026-06-06T00:22:35.089146Z","end":"2026-06-06T00:22:35.342579Z","steps":["trace[35761728] 'agreement among raft nodes before linearized reading' (duration: 253.278226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:35.342688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.356124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:35.342775Z","caller":"traceutil/trace.go:171","msg":"trace[632812936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26222; }","duration":"218.498727ms","start":"2026-06-06T00:22:35.124255Z","end":"2026-06-06T00:22:35.342754Z","steps":["trace[632812936] 'agreement among raft nodes before linearized reading' (duration: 218.353604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:35.342722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.370738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-06T00:22:35.342793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.518424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-06-06T00:22:35.342841Z","caller":"traceutil/trace.go:171","msg":"trace[221516075] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:26222; }","duration":"178.553221ms","start":"2026-06-06T00:22:35.164275Z","end":"2026-06-06T00:22:35.342829Z","steps":["trace[221516075] 'agreement among raft nodes before linearized reading' (duration: 178.402918ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:35.342873Z","caller":"traceutil/trace.go:171","msg":"trace[335310300] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26222; }","duration":"160.616256ms","start":"2026-06-06T00:22:35.182243Z","end":"2026-06-06T00:22:35.342859Z","steps":["trace[335310300] 'agreement among raft nodes before linearized reading' (duration: 160.363101ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:35.544763Z","caller":"traceutil/trace.go:171","msg":"trace[154974479] linearizableReadLoop","detail":"{readStateIndex:27077; appliedIndex:27076; }","duration":"192.368604ms","start":"2026-06-06T00:22:35.352366Z","end":"2026-06-06T00:22:35.544735Z","steps":["trace[154974479] 'read index received' (duration: 191.943886ms)","trace[154974479] 'applied index is now lower than readState.Index' (duration: 423.808µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:35.544946Z","caller":"traceutil/trace.go:171","msg":"trace[1078721053] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"195.56653ms","start":"2026-06-06T00:22:35.349335Z","end":"2026-06-06T00:22:35.544902Z","steps":["trace[1078721053] 'process raft request' (duration: 195.063238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:35.545057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.666811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-06-06T00:22:35.545272Z","caller":"traceutil/trace.go:171","msg":"trace[1000472962] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:26223; }","duration":"192.939896ms","start":"2026-06-06T00:22:35.352297Z","end":"2026-06-06T00:22:35.545237Z","steps":["trace[1000472962] 'agreement among raft nodes before linearized reading' (duration: 192.581609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:35.545036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.473415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11ff168a-9f5c-44b7-b104-47a3026ae73a\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-06T00:22:35.545571Z","caller":"traceutil/trace.go:171","msg":"trace[151986809] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11ff168a-9f5c-44b7-b104-47a3026ae73a; range_end:; response_count:1; response_revision:26223; }","duration":"140.071118ms","start":"2026-06-06T00:22:35.405481Z","end":"2026-06-06T00:22:35.545552Z","steps":["trace[151986809] 'agreement among raft nodes before linearized reading' (duration: 139.437925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:35.66135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.28946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1448"} {"level":"info","ts":"2026-06-06T00:22:35.661432Z","caller":"traceutil/trace.go:171","msg":"trace[1199804307] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26225; }","duration":"110.401112ms","start":"2026-06-06T00:22:35.551015Z","end":"2026-06-06T00:22:35.661416Z","steps":["trace[1199804307] 'agreement among raft nodes before linearized reading' (duration: 110.176197ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:35.801635Z","caller":"traceutil/trace.go:171","msg":"trace[1993734857] linearizableReadLoop","detail":"{readStateIndex:27080; appliedIndex:27079; }","duration":"135.403571ms","start":"2026-06-06T00:22:35.666204Z","end":"2026-06-06T00:22:35.801608Z","steps":["trace[1993734857] 'read index received' (duration: 105.554993ms)","trace[1993734857] 'applied index is now lower than readState.Index' (duration: 29.847218ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:35.801757Z","caller":"traceutil/trace.go:171","msg":"trace[1170470643] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"149.5175ms","start":"2026-06-06T00:22:35.652208Z","end":"2026-06-06T00:22:35.801725Z","steps":["trace[1170470643] 'process raft request' (duration: 119.565849ms)","trace[1170470643] 'compare' (duration: 29.695436ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:35.801951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.726058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-06-06T00:22:35.802007Z","caller":"traceutil/trace.go:171","msg":"trace[1491133828] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26226; }","duration":"135.81748ms","start":"2026-06-06T00:22:35.666176Z","end":"2026-06-06T00:22:35.801994Z","steps":["trace[1491133828] 'agreement among raft nodes before linearized reading' (duration: 135.539915ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:35.868016Z","caller":"traceutil/trace.go:171","msg":"trace[1804391424] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"106.577475ms","start":"2026-06-06T00:22:35.761389Z","end":"2026-06-06T00:22:35.867967Z","steps":["trace[1804391424] 'process raft request' (duration: 106.305219ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:35.868649Z","caller":"traceutil/trace.go:171","msg":"trace[1547348008] transaction","detail":"{read_only:false; response_revision:26228; number_of_response:1; }","duration":"101.48305ms","start":"2026-06-06T00:22:35.767142Z","end":"2026-06-06T00:22:35.868625Z","steps":["trace[1547348008] 'process raft request' (duration: 100.766605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:36.065524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.321236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:22:36.065647Z","caller":"traceutil/trace.go:171","msg":"trace[239608993] linearizableReadLoop","detail":"{readStateIndex:27083; appliedIndex:27082; }","duration":"184.013373ms","start":"2026-06-06T00:22:35.881615Z","end":"2026-06-06T00:22:36.065629Z","steps":["trace[239608993] 'read index received' (duration: 83.435222ms)","trace[239608993] 'applied index is now lower than readState.Index' (duration: 100.576991ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:36.065693Z","caller":"traceutil/trace.go:171","msg":"trace[1333993914] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"193.978067ms","start":"2026-06-06T00:22:35.871683Z","end":"2026-06-06T00:22:36.065661Z","steps":["trace[1333993914] 'process raft request' (duration: 93.410116ms)","trace[1333993914] 'compare' (duration: 100.173203ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:22:36.065916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.279339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-06-06T00:22:36.066113Z","caller":"traceutil/trace.go:171","msg":"trace[204282717] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26229; }","duration":"184.395152ms","start":"2026-06-06T00:22:35.881582Z","end":"2026-06-06T00:22:36.065977Z","steps":["trace[204282717] 'agreement among raft nodes before linearized reading' (duration: 184.130336ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:36.92436Z","caller":"traceutil/trace.go:171","msg":"trace[2004908536] linearizableReadLoop","detail":"{readStateIndex:27086; appliedIndex:27085; }","duration":"373.925449ms","start":"2026-06-06T00:22:36.550396Z","end":"2026-06-06T00:22:36.924321Z","steps":["trace[2004908536] 'read index received' (duration: 373.408448ms)","trace[2004908536] 'applied index is now lower than readState.Index' (duration: 515.701µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:22:36.924433Z","caller":"traceutil/trace.go:171","msg":"trace[617043335] transaction","detail":"{read_only:false; response_revision:26232; number_of_response:1; }","duration":"418.219761ms","start":"2026-06-06T00:22:36.506163Z","end":"2026-06-06T00:22:36.924382Z","steps":["trace[617043335] 'process raft request' (duration: 417.731791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:36.924658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:36.506123Z","time spent":"418.416815ms","remote":"127.0.0.1:35532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418437,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:22:36.924783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.341317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11ff168a-9f5c-44b7-b104-47a3026ae73a\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-06T00:22:36.924964Z","caller":"traceutil/trace.go:171","msg":"trace[1014314770] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11ff168a-9f5c-44b7-b104-47a3026ae73a; range_end:; response_count:1; response_revision:26232; }","duration":"374.605502ms","start":"2026-06-06T00:22:36.55034Z","end":"2026-06-06T00:22:36.924945Z","steps":["trace[1014314770] 'agreement among raft nodes before linearized reading' (duration: 374.117083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:36.925018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:36.550313Z","time spent":"374.694725ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11ff168a-9f5c-44b7-b104-47a3026ae73a\" "} {"level":"info","ts":"2026-06-06T00:22:36.928855Z","caller":"traceutil/trace.go:171","msg":"trace[1625494745] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"243.920246ms","start":"2026-06-06T00:22:36.684871Z","end":"2026-06-06T00:22:36.928791Z","steps":["trace[1625494745] 'process raft request' (duration: 243.695431ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:36.92913Z","caller":"traceutil/trace.go:171","msg":"trace[887065091] transaction","detail":"{read_only:false; response_revision:26234; number_of_response:1; }","duration":"243.711801ms","start":"2026-06-06T00:22:36.685381Z","end":"2026-06-06T00:22:36.929092Z","steps":["trace[887065091] 'process raft request' (duration: 243.356624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:36.929218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.792548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:36.929259Z","caller":"traceutil/trace.go:171","msg":"trace[1291832608] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"173.966319ms","start":"2026-06-06T00:22:36.755267Z","end":"2026-06-06T00:22:36.929233Z","steps":["trace[1291832608] 'process raft request' (duration: 173.833066ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:36.929294Z","caller":"traceutil/trace.go:171","msg":"trace[377909252] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26237; }","duration":"377.891709ms","start":"2026-06-06T00:22:36.551391Z","end":"2026-06-06T00:22:36.929283Z","steps":["trace[377909252] 'agreement among raft nodes before linearized reading' (duration: 377.762037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:36.929321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.359787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-06T00:22:36.929377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:22:36.551383Z","time spent":"377.986541ms","remote":"127.0.0.1:49772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"info","ts":"2026-06-06T00:22:36.929388Z","caller":"traceutil/trace.go:171","msg":"trace[634831332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26237; }","duration":"121.462499ms","start":"2026-06-06T00:22:36.807907Z","end":"2026-06-06T00:22:36.929369Z","steps":["trace[634831332] 'agreement among raft nodes before linearized reading' (duration: 121.349097ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:36.929407Z","caller":"traceutil/trace.go:171","msg":"trace[1749307239] transaction","detail":"{read_only:false; response_revision:26235; number_of_response:1; }","duration":"242.007257ms","start":"2026-06-06T00:22:36.687392Z","end":"2026-06-06T00:22:36.929399Z","steps":["trace[1749307239] 'process raft request' (duration: 241.584139ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:36.929434Z","caller":"traceutil/trace.go:171","msg":"trace[3890676] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"197.477789ms","start":"2026-06-06T00:22:36.731944Z","end":"2026-06-06T00:22:36.929422Z","steps":["trace[3890676] 'process raft request' (duration: 197.116302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:36.929466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.209841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:36.929492Z","caller":"traceutil/trace.go:171","msg":"trace[1877839026] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26237; }","duration":"139.274821ms","start":"2026-06-06T00:22:36.79021Z","end":"2026-06-06T00:22:36.929485Z","steps":["trace[1877839026] 'agreement among raft nodes before linearized reading' (duration: 139.22271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:22:41.379897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.107199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:22:41.379984Z","caller":"traceutil/trace.go:171","msg":"trace[1446063529] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26288; }","duration":"119.263252ms","start":"2026-06-06T00:22:41.2607Z","end":"2026-06-06T00:22:41.379964Z","steps":["trace[1446063529] 'count revisions from in-memory index tree' (duration: 119.014538ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:43.399843Z","caller":"traceutil/trace.go:171","msg":"trace[534115954] transaction","detail":"{read_only:false; response_revision:26308; number_of_response:1; }","duration":"121.55333ms","start":"2026-06-06T00:22:43.27825Z","end":"2026-06-06T00:22:43.399803Z","steps":["trace[534115954] 'process raft request' (duration: 121.331395ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:22:43.438282Z","caller":"traceutil/trace.go:171","msg":"trace[1742828518] transaction","detail":"{read_only:false; response_revision:26309; number_of_response:1; }","duration":"153.537132ms","start":"2026-06-06T00:22:43.284706Z","end":"2026-06-06T00:22:43.438243Z","steps":["trace[1742828518] 'process raft request' (duration: 153.218706ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:06.101356Z","caller":"traceutil/trace.go:171","msg":"trace[570000494] transaction","detail":"{read_only:false; response_revision:26490; number_of_response:1; }","duration":"108.314389ms","start":"2026-06-06T00:23:05.992993Z","end":"2026-06-06T00:23:06.101307Z","steps":["trace[570000494] 'process raft request' (duration: 62.201119ms)","trace[570000494] 'compare' (duration: 45.890996ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:06.192449Z","caller":"traceutil/trace.go:171","msg":"trace[308477667] transaction","detail":"{read_only:false; response_revision:26491; number_of_response:1; }","duration":"196.08442ms","start":"2026-06-06T00:23:05.996331Z","end":"2026-06-06T00:23:06.192416Z","steps":["trace[308477667] 'process raft request' (duration: 195.897136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:06.33801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.988148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:23:06.338395Z","caller":"traceutil/trace.go:171","msg":"trace[1789136135] linearizableReadLoop","detail":"{readStateIndex:27353; appliedIndex:27352; }","duration":"138.912614ms","start":"2026-06-06T00:23:06.199457Z","end":"2026-06-06T00:23:06.33837Z","steps":["trace[1789136135] 'read index received' (duration: 18.295594ms)","trace[1789136135] 'applied index is now lower than readState.Index' (duration: 120.61478ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:06.338424Z","caller":"traceutil/trace.go:171","msg":"trace[607771370] transaction","detail":"{read_only:false; response_revision:26492; number_of_response:1; }","duration":"149.621063ms","start":"2026-06-06T00:23:06.188767Z","end":"2026-06-06T00:23:06.338389Z","steps":["trace[607771370] 'process raft request' (duration: 29.117654ms)","trace[607771370] 'compare' (duration: 119.11504ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:23:06.338757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.295552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-06-06T00:23:06.338807Z","caller":"traceutil/trace.go:171","msg":"trace[1362023822] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26492; }","duration":"139.349733ms","start":"2026-06-06T00:23:06.199444Z","end":"2026-06-06T00:23:06.338794Z","steps":["trace[1362023822] 'agreement among raft nodes before linearized reading' (duration: 138.998986ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:06.896446Z","caller":"traceutil/trace.go:171","msg":"trace[1625028752] linearizableReadLoop","detail":"{readStateIndex:27354; appliedIndex:27353; }","duration":"150.070202ms","start":"2026-06-06T00:23:06.746348Z","end":"2026-06-06T00:23:06.896418Z","steps":["trace[1625028752] 'read index received' (duration: 149.914199ms)","trace[1625028752] 'applied index is now lower than readState.Index' (duration: 154.733µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:06.896546Z","caller":"traceutil/trace.go:171","msg":"trace[685560847] transaction","detail":"{read_only:false; response_revision:26493; number_of_response:1; }","duration":"179.266747ms","start":"2026-06-06T00:23:06.717255Z","end":"2026-06-06T00:23:06.896522Z","steps":["trace[685560847] 'process raft request' (duration: 179.003742ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:06.896666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.312467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-06-06T00:23:06.896695Z","caller":"traceutil/trace.go:171","msg":"trace[1557169563] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:26493; }","duration":"150.414498ms","start":"2026-06-06T00:23:06.746269Z","end":"2026-06-06T00:23:06.896684Z","steps":["trace[1557169563] 'agreement among raft nodes before linearized reading' (duration: 150.272716ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:07.23231Z","caller":"traceutil/trace.go:171","msg":"trace[980257761] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"180.112783ms","start":"2026-06-06T00:23:07.052162Z","end":"2026-06-06T00:23:07.232275Z","steps":["trace[980257761] 'process raft request' (duration: 180.015482ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:07.232792Z","caller":"traceutil/trace.go:171","msg":"trace[1362290465] transaction","detail":"{read_only:false; response_revision:26495; number_of_response:1; }","duration":"189.162668ms","start":"2026-06-06T00:23:07.04361Z","end":"2026-06-06T00:23:07.232773Z","steps":["trace[1362290465] 'process raft request' (duration: 188.376403ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:07.232815Z","caller":"traceutil/trace.go:171","msg":"trace[1484630744] transaction","detail":"{read_only:false; response_revision:26494; number_of_response:1; }","duration":"189.822663ms","start":"2026-06-06T00:23:07.042965Z","end":"2026-06-06T00:23:07.232788Z","steps":["trace[1484630744] 'process raft request' (duration: 138.760212ms)","trace[1484630744] 'compare' (duration: 49.888677ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:07.232828Z","caller":"traceutil/trace.go:171","msg":"trace[1322131880] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"186.970914ms","start":"2026-06-06T00:23:07.045841Z","end":"2026-06-06T00:23:07.232812Z","steps":["trace[1322131880] 'process raft request' (duration: 186.25773ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:07.232898Z","caller":"traceutil/trace.go:171","msg":"trace[1994434827] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"182.568755ms","start":"2026-06-06T00:23:07.050263Z","end":"2026-06-06T00:23:07.232831Z","steps":["trace[1994434827] 'process raft request' (duration: 181.874621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:07.526275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.826295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:23:07.526494Z","caller":"traceutil/trace.go:171","msg":"trace[1515664182] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"444.554169ms","start":"2026-06-06T00:23:07.081882Z","end":"2026-06-06T00:23:07.526436Z","steps":["trace[1515664182] 'process raft request' (duration: 274.253665ms)","trace[1515664182] 'compare' (duration: 169.57563ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:07.526445Z","caller":"traceutil/trace.go:171","msg":"trace[230750501] linearizableReadLoop","detail":"{readStateIndex:27361; appliedIndex:27360; }","duration":"296.749644ms","start":"2026-06-06T00:23:07.229672Z","end":"2026-06-06T00:23:07.526421Z","steps":["trace[230750501] 'read index received' (duration: 126.555722ms)","trace[230750501] 'applied index is now lower than readState.Index' (duration: 170.192282ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:23:07.526731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:23:07.081848Z","time spent":"444.756043ms","remote":"127.0.0.1:54016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:23:07.526848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.148842ms","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-06T00:23:07.526943Z","caller":"traceutil/trace.go:171","msg":"trace[105480452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26499; }","duration":"297.257664ms","start":"2026-06-06T00:23:07.229663Z","end":"2026-06-06T00:23:07.526921Z","steps":["trace[105480452] 'agreement among raft nodes before linearized reading' (duration: 296.877226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:07.527273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.763701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T00:23:07.527342Z","caller":"traceutil/trace.go:171","msg":"trace[439140688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26499; }","duration":"214.895613ms","start":"2026-06-06T00:23:07.31243Z","end":"2026-06-06T00:23:07.527325Z","steps":["trace[439140688] 'agreement among raft nodes before linearized reading' (duration: 214.685669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:07.527444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.488157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-06T00:23:07.527489Z","caller":"traceutil/trace.go:171","msg":"trace[113239791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26499; }","duration":"219.583189ms","start":"2026-06-06T00:23:07.307893Z","end":"2026-06-06T00:23:07.527476Z","steps":["trace[113239791] 'agreement among raft nodes before linearized reading' (duration: 219.172791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:07.527518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.816042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-06T00:23:07.527951Z","caller":"traceutil/trace.go:171","msg":"trace[197598054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26499; }","duration":"214.302241ms","start":"2026-06-06T00:23:07.31363Z","end":"2026-06-06T00:23:07.527932Z","steps":["trace[197598054] 'agreement among raft nodes before linearized reading' (duration: 213.432264ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:07.640384Z","caller":"traceutil/trace.go:171","msg":"trace[702658396] linearizableReadLoop","detail":"{readStateIndex:27362; appliedIndex:27361; }","duration":"109.320431ms","start":"2026-06-06T00:23:07.53103Z","end":"2026-06-06T00:23:07.64035Z","steps":["trace[702658396] 'read index received' (duration: 108.36462ms)","trace[702658396] 'applied index is now lower than readState.Index' (duration: 952.3µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:07.640641Z","caller":"traceutil/trace.go:171","msg":"trace[1200827776] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"109.271179ms","start":"2026-06-06T00:23:07.531018Z","end":"2026-06-06T00:23:07.640289Z","steps":["trace[1200827776] 'process raft request' (duration: 108.36315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:07.640862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.751949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-06T00:23:07.641033Z","caller":"traceutil/trace.go:171","msg":"trace[634286976] transaction","detail":"{read_only:false; response_revision:26501; number_of_response:1; }","duration":"109.32382ms","start":"2026-06-06T00:23:07.531693Z","end":"2026-06-06T00:23:07.641017Z","steps":["trace[634286976] 'process raft request' (duration: 108.770719ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:07.641032Z","caller":"traceutil/trace.go:171","msg":"trace[22842000] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26500; }","duration":"110.005354ms","start":"2026-06-06T00:23:07.531Z","end":"2026-06-06T00:23:07.641005Z","steps":["trace[22842000] 'agreement among raft nodes before linearized reading' (duration: 109.463573ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:07.641195Z","caller":"traceutil/trace.go:171","msg":"trace[523092097] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"108.036064ms","start":"2026-06-06T00:23:07.533141Z","end":"2026-06-06T00:23:07.641177Z","steps":["trace[523092097] 'process raft request' (duration: 107.795509ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:07.641398Z","caller":"traceutil/trace.go:171","msg":"trace[2043615710] transaction","detail":"{read_only:false; response_revision:26503; number_of_response:1; }","duration":"107.80177ms","start":"2026-06-06T00:23:07.533577Z","end":"2026-06-06T00:23:07.641379Z","steps":["trace[2043615710] 'process raft request' (duration: 107.415082ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:09.364597Z","caller":"traceutil/trace.go:171","msg":"trace[313143356] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"126.515802ms","start":"2026-06-06T00:23:09.238044Z","end":"2026-06-06T00:23:09.36456Z","steps":["trace[313143356] 'process raft request' (duration: 126.299487ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:09.657424Z","caller":"traceutil/trace.go:171","msg":"trace[562571478] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"418.953507ms","start":"2026-06-06T00:23:09.238434Z","end":"2026-06-06T00:23:09.657388Z","steps":["trace[562571478] 'process raft request' (duration: 406.479042ms)","trace[562571478] 'compare' (duration: 11.699239ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:09.657519Z","caller":"traceutil/trace.go:171","msg":"trace[1878613102] linearizableReadLoop","detail":"{readStateIndex:27374; appliedIndex:27370; }","duration":"231.284648ms","start":"2026-06-06T00:23:09.426203Z","end":"2026-06-06T00:23:09.657488Z","steps":["trace[1878613102] 'read index received' (duration: 218.594489ms)","trace[1878613102] 'applied index is now lower than readState.Index' (duration: 12.688959ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T00:23:09.657595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:23:09.238394Z","time spent":"419.115441ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:23:09.657767Z","caller":"traceutil/trace.go:171","msg":"trace[1383474823] transaction","detail":"{read_only:false; response_revision:26512; number_of_response:1; }","duration":"418.628681ms","start":"2026-06-06T00:23:09.239127Z","end":"2026-06-06T00:23:09.657756Z","steps":["trace[1383474823] 'process raft request' (duration: 418.311434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:09.657856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:23:09.239115Z","time spent":"418.683581ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:23:09.65799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.780589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-06T00:23:09.658014Z","caller":"traceutil/trace.go:171","msg":"trace[845510862] transaction","detail":"{read_only:false; response_revision:26510; number_of_response:1; }","duration":"419.283393ms","start":"2026-06-06T00:23:09.238716Z","end":"2026-06-06T00:23:09.657999Z","steps":["trace[845510862] 'process raft request' (duration: 418.536279ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:09.658042Z","caller":"traceutil/trace.go:171","msg":"trace[1484915151] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26512; }","duration":"231.913471ms","start":"2026-06-06T00:23:09.426116Z","end":"2026-06-06T00:23:09.658029Z","steps":["trace[1484915151] 'agreement among raft nodes before linearized reading' (duration: 231.736468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:09.65808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:23:09.238685Z","time spent":"419.354545ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T00:23:09.658216Z","caller":"traceutil/trace.go:171","msg":"trace[1071035721] transaction","detail":"{read_only:false; response_revision:26511; number_of_response:1; }","duration":"419.390466ms","start":"2026-06-06T00:23:09.238815Z","end":"2026-06-06T00:23:09.658206Z","steps":["trace[1071035721] 'process raft request' (duration: 418.553399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:09.658278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T00:23:09.238777Z","time spent":"419.470197ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T00:23:09.921297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.346245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:23:09.921468Z","caller":"traceutil/trace.go:171","msg":"trace[1584591363] transaction","detail":"{read_only:false; response_revision:26513; number_of_response:1; }","duration":"258.011443ms","start":"2026-06-06T00:23:09.663428Z","end":"2026-06-06T00:23:09.92144Z","steps":["trace[1584591363] 'process raft request' (duration: 113.378212ms)","trace[1584591363] 'compare' (duration: 144.11154ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:09.929733Z","caller":"traceutil/trace.go:171","msg":"trace[1059378521] transaction","detail":"{read_only:false; response_revision:26514; number_of_response:1; }","duration":"265.926414ms","start":"2026-06-06T00:23:09.663787Z","end":"2026-06-06T00:23:09.929713Z","steps":["trace[1059378521] 'process raft request' (duration: 265.755721ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:09.929974Z","caller":"traceutil/trace.go:171","msg":"trace[297714707] linearizableReadLoop","detail":"{readStateIndex:27377; appliedIndex:27374; }","duration":"265.771961ms","start":"2026-06-06T00:23:09.664184Z","end":"2026-06-06T00:23:09.929956Z","steps":["trace[297714707] 'read index received' (duration: 112.695538ms)","trace[297714707] 'applied index is now lower than readState.Index' (duration: 153.075203ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:09.929977Z","caller":"traceutil/trace.go:171","msg":"trace[857056066] transaction","detail":"{read_only:false; response_revision:26515; number_of_response:1; }","duration":"265.971945ms","start":"2026-06-06T00:23:09.663986Z","end":"2026-06-06T00:23:09.929958Z","steps":["trace[857056066] 'process raft request' (duration: 265.69171ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:09.930037Z","caller":"traceutil/trace.go:171","msg":"trace[1966644584] transaction","detail":"{read_only:false; response_revision:26516; number_of_response:1; }","duration":"265.516016ms","start":"2026-06-06T00:23:09.664491Z","end":"2026-06-06T00:23:09.930007Z","steps":["trace[1966644584] 'process raft request' (duration: 265.389754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:09.93018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.987935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-06T00:23:09.930215Z","caller":"traceutil/trace.go:171","msg":"trace[1042172963] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26516; }","duration":"266.115668ms","start":"2026-06-06T00:23:09.664088Z","end":"2026-06-06T00:23:09.930204Z","steps":["trace[1042172963] 'agreement among raft nodes before linearized reading' (duration: 265.959105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:09.930265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.994988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:23:09.930397Z","caller":"traceutil/trace.go:171","msg":"trace[1546296937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26516; }","duration":"196.189812ms","start":"2026-06-06T00:23:09.734169Z","end":"2026-06-06T00:23:09.930359Z","steps":["trace[1546296937] 'agreement among raft nodes before linearized reading' (duration: 195.967218ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:10.363643Z","caller":"traceutil/trace.go:171","msg":"trace[437844750] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"126.49379ms","start":"2026-06-06T00:23:10.237118Z","end":"2026-06-06T00:23:10.363612Z","steps":["trace[437844750] 'process raft request' (duration: 124.855196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:10.665045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.478531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:23:10.665872Z","caller":"traceutil/trace.go:171","msg":"trace[2091690755] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"269.631641ms","start":"2026-06-06T00:23:10.39593Z","end":"2026-06-06T00:23:10.665562Z","steps":["trace[2091690755] 'process raft request' (duration: 89.473565ms)","trace[2091690755] 'compare' (duration: 179.122314ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:19.097886Z","caller":"traceutil/trace.go:171","msg":"trace[2137569847] transaction","detail":"{read_only:false; response_revision:26592; number_of_response:1; }","duration":"136.934404ms","start":"2026-06-06T00:23:18.960916Z","end":"2026-06-06T00:23:19.097851Z","steps":["trace[2137569847] 'process raft request' (duration: 136.675398ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:36.572128Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006bc5.wal"} {"level":"info","ts":"2026-06-06T00:23:41.958222Z","caller":"traceutil/trace.go:171","msg":"trace[923991560] transaction","detail":"{read_only:false; response_revision:26758; number_of_response:1; }","duration":"128.241816ms","start":"2026-06-06T00:23:41.829946Z","end":"2026-06-06T00:23:41.958188Z","steps":["trace[923991560] 'process raft request' (duration: 128.185555ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:41.958312Z","caller":"traceutil/trace.go:171","msg":"trace[842403429] transaction","detail":"{read_only:false; response_revision:26756; number_of_response:1; }","duration":"129.178935ms","start":"2026-06-06T00:23:41.829059Z","end":"2026-06-06T00:23:41.958238Z","steps":["trace[842403429] 'process raft request' (duration: 128.874279ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:41.958315Z","caller":"traceutil/trace.go:171","msg":"trace[85282905] transaction","detail":"{read_only:false; response_revision:26757; number_of_response:1; }","duration":"129.066533ms","start":"2026-06-06T00:23:41.829199Z","end":"2026-06-06T00:23:41.958265Z","steps":["trace[85282905] 'process raft request' (duration: 128.888319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:42.110516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.488093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:23:42.110835Z","caller":"traceutil/trace.go:171","msg":"trace[197363018] transaction","detail":"{read_only:false; response_revision:26759; number_of_response:1; }","duration":"230.39545ms","start":"2026-06-06T00:23:41.880408Z","end":"2026-06-06T00:23:42.110803Z","steps":["trace[197363018] 'process raft request' (duration: 121.492039ms)","trace[197363018] 'compare' (duration: 108.159196ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:42.282023Z","caller":"traceutil/trace.go:171","msg":"trace[116654567] transaction","detail":"{read_only:false; response_revision:26763; number_of_response:1; }","duration":"165.334593ms","start":"2026-06-06T00:23:42.116649Z","end":"2026-06-06T00:23:42.281984Z","steps":["trace[116654567] 'process raft request' (duration: 165.258321ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:42.28205Z","caller":"traceutil/trace.go:171","msg":"trace[1478322390] transaction","detail":"{read_only:false; response_revision:26761; number_of_response:1; }","duration":"165.928315ms","start":"2026-06-06T00:23:42.116063Z","end":"2026-06-06T00:23:42.281991Z","steps":["trace[1478322390] 'process raft request' (duration: 165.564167ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:42.282092Z","caller":"traceutil/trace.go:171","msg":"trace[1258287987] transaction","detail":"{read_only:false; response_revision:26762; number_of_response:1; }","duration":"165.464725ms","start":"2026-06-06T00:23:42.116582Z","end":"2026-06-06T00:23:42.282046Z","steps":["trace[1258287987] 'process raft request' (duration: 165.281372ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:42.282231Z","caller":"traceutil/trace.go:171","msg":"trace[1454034298] linearizableReadLoop","detail":"{readStateIndex:27632; appliedIndex:27627; }","duration":"130.427051ms","start":"2026-06-06T00:23:42.151774Z","end":"2026-06-06T00:23:42.282201Z","steps":["trace[1454034298] 'read index received' (duration: 6.456912ms)","trace[1454034298] 'applied index is now lower than readState.Index' (duration: 123.964629ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:42.282285Z","caller":"traceutil/trace.go:171","msg":"trace[1592534168] transaction","detail":"{read_only:false; response_revision:26764; number_of_response:1; }","duration":"165.449544ms","start":"2026-06-06T00:23:42.116805Z","end":"2026-06-06T00:23:42.282255Z","steps":["trace[1592534168] 'process raft request' (duration: 165.136878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:42.282384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.574864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:23:42.282446Z","caller":"traceutil/trace.go:171","msg":"trace[472663055] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26764; }","duration":"130.667296ms","start":"2026-06-06T00:23:42.151757Z","end":"2026-06-06T00:23:42.282425Z","steps":["trace[472663055] 'agreement among raft nodes before linearized reading' (duration: 130.536903ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:23:45.402351Z","caller":"traceutil/trace.go:171","msg":"trace[1914238384] transaction","detail":"{read_only:false; response_revision:26781; number_of_response:1; }","duration":"200.732984ms","start":"2026-06-06T00:23:45.201572Z","end":"2026-06-06T00:23:45.402305Z","steps":["trace[1914238384] 'process raft request' (duration: 116.452595ms)","trace[1914238384] 'compare' (duration: 83.999543ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:45.704958Z","caller":"traceutil/trace.go:171","msg":"trace[1149525341] transaction","detail":"{read_only:false; response_revision:26782; number_of_response:1; }","duration":"110.78629ms","start":"2026-06-06T00:23:45.594121Z","end":"2026-06-06T00:23:45.704907Z","steps":["trace[1149525341] 'process raft request' (duration: 110.489394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:45.901593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.06382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T00:23:45.901784Z","caller":"traceutil/trace.go:171","msg":"trace[1096393477] transaction","detail":"{read_only:false; response_revision:26783; number_of_response:1; }","duration":"183.997774ms","start":"2026-06-06T00:23:45.717741Z","end":"2026-06-06T00:23:45.901739Z","steps":["trace[1096393477] 'process raft request' (duration: 60.684598ms)","trace[1096393477] 'compare' (duration: 122.847026ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T00:23:45.990154Z","caller":"traceutil/trace.go:171","msg":"trace[1096942767] transaction","detail":"{read_only:false; response_revision:26784; number_of_response:1; }","duration":"111.825871ms","start":"2026-06-06T00:23:45.878315Z","end":"2026-06-06T00:23:45.990141Z","steps":["trace[1096942767] 'process raft request' (duration: 111.581677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:45.990241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.345369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:23:45.990335Z","caller":"traceutil/trace.go:171","msg":"trace[2107511839] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26784; }","duration":"104.519302ms","start":"2026-06-06T00:23:45.885791Z","end":"2026-06-06T00:23:45.99031Z","steps":["trace[2107511839] 'agreement among raft nodes before linearized reading' (duration: 104.355109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T00:23:45.990318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.43074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:23:45.991018Z","caller":"traceutil/trace.go:171","msg":"trace[1854597494] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26784; }","duration":"177.594363ms","start":"2026-06-06T00:23:45.812802Z","end":"2026-06-06T00:23:45.990396Z","steps":["trace[1854597494] 'agreement among raft nodes before linearized reading' (duration: 177.369458ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:25:53.771284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25270} {"level":"info","ts":"2026-06-06T00:25:53.916003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25270,"took":"139.013056ms","hash":545391330,"current-db-size-bytes":60583936,"current-db-size":"61 MB","current-db-size-in-use-bytes":37187584,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-06T00:25:53.916066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":545391330,"revision":25270,"compact-revision":22042} {"level":"warn","ts":"2026-06-06T00:27:07.193008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.901437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T00:27:07.193101Z","caller":"traceutil/trace.go:171","msg":"trace[1771698149] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:28799; }","duration":"104.07138ms","start":"2026-06-06T00:27:07.089011Z","end":"2026-06-06T00:27:07.193083Z","steps":["trace[1771698149] 'count revisions from in-memory index tree' (duration: 103.772295ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T00:27:32.676871Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":30004,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-06T00:27:32.682831Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-06-06T00:27:32.682995Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-06-06T00:30:53.778592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28059} {"level":"info","ts":"2026-06-06T00:30:53.924909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28059,"took":"141.064004ms","hash":2545215334,"current-db-size-bytes":60583936,"current-db-size":"61 MB","current-db-size-in-use-bytes":33816576,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-06T00:30:53.924994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2545215334,"revision":28059,"compact-revision":25270} {"level":"info","ts":"2026-06-06T00:35:53.785638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30618} {"level":"info","ts":"2026-06-06T00:35:53.926764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30618,"took":"133.930585ms","hash":312531708,"current-db-size-bytes":60583936,"current-db-size":"61 MB","current-db-size-in-use-bytes":30355456,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-06T00:35:53.926853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":312531708,"revision":30618,"compact-revision":28059} {"level":"info","ts":"2026-06-06T00:40:53.79374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32868} {"level":"info","ts":"2026-06-06T00:40:53.945597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32868,"took":"145.475051ms","hash":3766040951,"current-db-size-bytes":60583936,"current-db-size":"61 MB","current-db-size-in-use-bytes":30085120,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-06T00:40:53.945666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3766040951,"revision":32868,"compact-revision":30618} {"level":"info","ts":"2026-06-06T00:45:53.807066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35091} {"level":"info","ts":"2026-06-06T00:45:53.964491Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35091,"took":"146.817237ms","hash":923041680,"current-db-size-bytes":60583936,"current-db-size":"61 MB","current-db-size-in-use-bytes":31330304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-06T00:45:53.964596Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":923041680,"revision":35091,"compact-revision":32868} {"level":"info","ts":"2026-06-06T00:49:02.394123Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-06T00:49:02.398712Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-06-06T00:49:02.398813Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-06-06T00:50:53.816003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37385} {"level":"info","ts":"2026-06-06T00:50:53.943246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37385,"took":"123.234885ms","hash":2982415839,"current-db-size-bytes":60583936,"current-db-size":"61 MB","current-db-size-in-use-bytes":31485952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-06T00:50:53.94338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2982415839,"revision":37385,"compact-revision":35091} {"level":"info","ts":"2026-06-06T00:55:53.8232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39658} {"level":"info","ts":"2026-06-06T00:55:53.957274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39658,"took":"128.54006ms","hash":3113341817,"current-db-size-bytes":60583936,"current-db-size":"61 MB","current-db-size-in-use-bytes":31064064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-06T00:55:53.957341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3113341817,"revision":39658,"compact-revision":37385}