{"level":"warn","ts":"2026-06-19T18:04:52.413007Z","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-19T18:04:52.413175Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-19T18:04:52.413301Z","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-19T18:04:52.41339Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-06-19T18:04:52.41343Z","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-19T18:04:52.415438Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-06-19T18:04:52.415664Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-19T18:04:52.418711Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.733178ms"} {"level":"info","ts":"2026-06-19T18:04:52.428017Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-06-19T18:04:52.428088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-06-19T18:04:52.428114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-06-19T18:04:52.428124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-19T18:04:52.428136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-06-19T18:04:52.428179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-06-19T18:04:52.434502Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-19T18:04:52.438685Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-19T18:04:52.440225Z","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-19T18:04:52.444017Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-19T18:04:52.444238Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-19T18:04:52.444412Z","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-19T18:04:52.444561Z","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-19T18:04:52.444585Z","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-19T18:04:52.445384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-06-19T18:04:52.445518Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-06-19T18:04:52.4473Z","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-19T18:04:52.447376Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-06-19T18:04:52.447401Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-06-19T18:04:52.447567Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-19T18:04:52.44762Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-19T18:04:52.629102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-06-19T18:04:52.629169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-06-19T18:04:52.629192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-06-19T18:04:52.629212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-06-19T18:04:52.629224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-06-19T18:04:52.629262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-06-19T18:04:52.629278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-06-19T18:04:52.630396Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T18:04:52.631113Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-06-19T18:04:52.631144Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-19T18:04:52.631211Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-19T18:04:52.631485Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-19T18:04:52.631558Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-19T18:04:52.631981Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T18:04:52.632114Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T18:04:52.632162Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T18:04:52.636362Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-19T18:04:52.636681Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-06-19T18:05:10.786317Z","caller":"traceutil/trace.go:171","msg":"trace[219467065] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"271.152211ms","start":"2026-06-19T18:05:10.515135Z","end":"2026-06-19T18:05:10.786287Z","steps":["trace[219467065] 'process raft request' (duration: 270.982167ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:05:31.301502Z","caller":"traceutil/trace.go:171","msg":"trace[875268136] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"202.954315ms","start":"2026-06-19T18:05:31.098496Z","end":"2026-06-19T18:05:31.30145Z","steps":["trace[875268136] 'process raft request' (duration: 181.448199ms)","trace[875268136] 'compare' (duration: 21.329412ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:05:31.304973Z","caller":"traceutil/trace.go:171","msg":"trace[1529274437] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"191.611658ms","start":"2026-06-19T18:05:31.113328Z","end":"2026-06-19T18:05:31.304939Z","steps":["trace[1529274437] 'process raft request' (duration: 188.43221ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:05:31.307332Z","caller":"traceutil/trace.go:171","msg":"trace[286839448] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:578; }","duration":"128.398105ms","start":"2026-06-19T18:05:31.178832Z","end":"2026-06-19T18:05:31.30723Z","steps":["trace[286839448] 'read index received' (duration: 101.152409ms)","trace[286839448] 'applied index is now lower than readState.Index' (duration: 27.243406ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:05:31.307412Z","caller":"traceutil/trace.go:171","msg":"trace[1701871757] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"166.111296ms","start":"2026-06-19T18:05:31.141262Z","end":"2026-06-19T18:05:31.307373Z","steps":["trace[1701871757] 'process raft request' (duration: 164.730643ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:05:31.307683Z","caller":"traceutil/trace.go:171","msg":"trace[127812737] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"188.039951ms","start":"2026-06-19T18:05:31.117331Z","end":"2026-06-19T18:05:31.305371Z","steps":["trace[127812737] 'process raft request' (duration: 186.159176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:05:31.308268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.130993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:05:31.308347Z","caller":"traceutil/trace.go:171","msg":"trace[1484441627] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:557; }","duration":"129.259227ms","start":"2026-06-19T18:05:31.179069Z","end":"2026-06-19T18:05:31.308328Z","steps":["trace[1484441627] 'agreement among raft nodes before linearized reading' (duration: 128.555599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:05:31.308461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.422319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:05:31.308544Z","caller":"traceutil/trace.go:171","msg":"trace[490062993] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:557; }","duration":"129.725388ms","start":"2026-06-19T18:05:31.178795Z","end":"2026-06-19T18:05:31.30852Z","steps":["trace[490062993] 'agreement among raft nodes before linearized reading' (duration: 128.657341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:05:31.308445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.484172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:05:31.308631Z","caller":"traceutil/trace.go:171","msg":"trace[407616521] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:557; }","duration":"129.736638ms","start":"2026-06-19T18:05:31.178874Z","end":"2026-06-19T18:05:31.308611Z","steps":["trace[407616521] 'agreement among raft nodes before linearized reading' (duration: 128.752203ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:05:38.091105Z","caller":"traceutil/trace.go:171","msg":"trace[1225957735] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"267.784769ms","start":"2026-06-19T18:05:37.823287Z","end":"2026-06-19T18:05:38.091072Z","steps":["trace[1225957735] 'process raft request' (duration: 267.538633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:05:38.699541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.56435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-19T18:05:38.699968Z","caller":"traceutil/trace.go:171","msg":"trace[1916288729] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"594.412773ms","start":"2026-06-19T18:05:38.10553Z","end":"2026-06-19T18:05:38.699943Z","steps":["trace[1916288729] 'process raft request' (duration: 27.748017ms)","trace[1916288729] 'compare' (duration: 565.271583ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:05:38.700048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:05:38.105512Z","time spent":"594.509775ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-19T18:05:38.700042Z","caller":"traceutil/trace.go:171","msg":"trace[1765643114] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"409.833816ms","start":"2026-06-19T18:05:38.290133Z","end":"2026-06-19T18:05:38.699967Z","steps":["trace[1765643114] 'read index received' (duration: 138.545592ms)","trace[1765643114] 'applied index is now lower than readState.Index' (duration: 271.275054ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:05:38.700116Z","caller":"traceutil/trace.go:171","msg":"trace[391242302] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"593.733257ms","start":"2026-06-19T18:05:38.106366Z","end":"2026-06-19T18:05:38.700099Z","steps":["trace[391242302] 'process raft request' (duration: 593.327797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:05:38.700284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.143364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-19T18:05:38.700313Z","caller":"traceutil/trace.go:171","msg":"trace[1919188157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"410.213115ms","start":"2026-06-19T18:05:38.290093Z","end":"2026-06-19T18:05:38.700306Z","steps":["trace[1919188157] 'agreement among raft nodes before linearized reading' (duration: 410.089172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:05:38.700334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:05:38.290062Z","time spent":"410.267477ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T18:05:38.700351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.172478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:05:38.700395Z","caller":"traceutil/trace.go:171","msg":"trace[371062745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"318.313572ms","start":"2026-06-19T18:05:38.382068Z","end":"2026-06-19T18:05:38.700381Z","steps":["trace[371062745] 'agreement among raft nodes before linearized reading' (duration: 318.175009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:05:38.700411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:05:38.10634Z","time spent":"593.82059ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T18:05:38.700448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:05:38.382039Z","time spent":"318.399393ms","remote":"127.0.0.1:52850","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-19T18:05:53.874143Z","caller":"traceutil/trace.go:171","msg":"trace[654088326] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"156.181804ms","start":"2026-06-19T18:05:53.717923Z","end":"2026-06-19T18:05:53.874104Z","steps":["trace[654088326] 'process raft request' (duration: 155.972459ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:07:10.463814Z","caller":"traceutil/trace.go:171","msg":"trace[673509131] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"175.689536ms","start":"2026-06-19T18:07:10.288088Z","end":"2026-06-19T18:07:10.463777Z","steps":["trace[673509131] 'read index received' (duration: 175.44756ms)","trace[673509131] 'applied index is now lower than readState.Index' (duration: 240.506µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:07:10.463945Z","caller":"traceutil/trace.go:171","msg":"trace[1984377687] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"196.892826ms","start":"2026-06-19T18:07:10.267014Z","end":"2026-06-19T18:07:10.463907Z","steps":["trace[1984377687] 'process raft request' (duration: 196.561368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:10.464142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.037154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-19T18:07:10.464189Z","caller":"traceutil/trace.go:171","msg":"trace[564640449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1870; }","duration":"176.107207ms","start":"2026-06-19T18:07:10.28807Z","end":"2026-06-19T18:07:10.464177Z","steps":["trace[564640449] 'agreement among raft nodes before linearized reading' (duration: 175.897572ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:07:10.613732Z","caller":"traceutil/trace.go:171","msg":"trace[970497765] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"144.09945ms","start":"2026-06-19T18:07:10.469588Z","end":"2026-06-19T18:07:10.613687Z","steps":["trace[970497765] 'process raft request' (duration: 124.280733ms)","trace[970497765] 'compare' (duration: 19.462118ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:07:25.009925Z","caller":"traceutil/trace.go:171","msg":"trace[1565036488] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"339.611975ms","start":"2026-06-19T18:07:24.670274Z","end":"2026-06-19T18:07:25.009886Z","steps":["trace[1565036488] 'process raft request' (duration: 338.281253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:25.010194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:24.670252Z","time spent":"339.826099ms","remote":"127.0.0.1:53292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T18:07:25.012817Z","caller":"traceutil/trace.go:171","msg":"trace[855721448] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"342.406522ms","start":"2026-06-19T18:07:24.670339Z","end":"2026-06-19T18:07:25.012745Z","steps":["trace[855721448] 'process raft request' (duration: 342.094075ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:07:25.013084Z","caller":"traceutil/trace.go:171","msg":"trace[1661539455] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2072; }","duration":"319.234867ms","start":"2026-06-19T18:07:24.693836Z","end":"2026-06-19T18:07:25.013071Z","steps":["trace[1661539455] 'read index received' (duration: 314.742358ms)","trace[1661539455] 'applied index is now lower than readState.Index' (duration: 4.491649ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:07:25.013166Z","caller":"traceutil/trace.go:171","msg":"trace[1935484661] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"342.7263ms","start":"2026-06-19T18:07:24.670433Z","end":"2026-06-19T18:07:25.01316Z","steps":["trace[1935484661] 'process raft request' (duration: 342.250379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:25.013158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:24.670309Z","time spent":"342.753251ms","remote":"127.0.0.1:53150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T18:07:25.013208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:24.670407Z","time spent":"342.771631ms","remote":"127.0.0.1:52978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1231,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T18:07:25.013283Z","caller":"traceutil/trace.go:171","msg":"trace[1585007599] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"328.691427ms","start":"2026-06-19T18:07:24.684548Z","end":"2026-06-19T18:07:25.01324Z","steps":["trace[1585007599] 'process raft request' (duration: 328.479062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:25.013444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.613757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-19T18:07:25.013467Z","caller":"traceutil/trace.go:171","msg":"trace[1546845468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1998; }","duration":"319.75969ms","start":"2026-06-19T18:07:24.693701Z","end":"2026-06-19T18:07:25.013461Z","steps":["trace[1546845468] 'agreement among raft nodes before linearized reading' (duration: 319.640307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:25.013488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:24.69368Z","time spent":"319.803172ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-19T18:07:25.013533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:24.684531Z","time spent":"328.84807ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T18:07:25.01365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.646651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:07:25.0137Z","caller":"traceutil/trace.go:171","msg":"trace[1034024885] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"343.243923ms","start":"2026-06-19T18:07:24.67045Z","end":"2026-06-19T18:07:25.013693Z","steps":["trace[1034024885] 'process raft request' (duration: 342.468383ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:07:25.013715Z","caller":"traceutil/trace.go:171","msg":"trace[1477203699] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1998; }","duration":"198.761744ms","start":"2026-06-19T18:07:24.81494Z","end":"2026-06-19T18:07:25.013701Z","steps":["trace[1477203699] 'agreement among raft nodes before linearized reading' (duration: 198.659672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:25.013735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:24.670425Z","time spent":"343.291415ms","remote":"127.0.0.1:53096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1847,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T18:08:02.120269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.225821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T18:08:02.120837Z","caller":"traceutil/trace.go:171","msg":"trace[1278606367] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"211.763142ms","start":"2026-06-19T18:08:01.909038Z","end":"2026-06-19T18:08:02.120801Z","steps":["trace[1278606367] 'process raft request' (duration: 211.368912ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:08:02.120864Z","caller":"traceutil/trace.go:171","msg":"trace[1975402625] linearizableReadLoop","detail":"{readStateIndex:2297; appliedIndex:2295; }","duration":"153.591382ms","start":"2026-06-19T18:08:01.967153Z","end":"2026-06-19T18:08:02.120745Z","steps":["trace[1975402625] 'read index received' (duration: 15.784536ms)","trace[1975402625] 'applied index is now lower than readState.Index' (duration: 137.800156ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:08:02.121137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.92677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:08:02.121149Z","caller":"traceutil/trace.go:171","msg":"trace[2147344854] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"214.220853ms","start":"2026-06-19T18:08:01.90623Z","end":"2026-06-19T18:08:02.120451Z","steps":["trace[2147344854] 'process raft request' (duration: 76.694223ms)","trace[2147344854] 'compare' (duration: 137.103819ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:08:02.121331Z","caller":"traceutil/trace.go:171","msg":"trace[244983042] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2209; }","duration":"154.152735ms","start":"2026-06-19T18:08:01.967095Z","end":"2026-06-19T18:08:02.121248Z","steps":["trace[244983042] 'agreement among raft nodes before linearized reading' (duration: 153.834337ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:08:02.137321Z","caller":"traceutil/trace.go:171","msg":"trace[1978748733] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"121.118458ms","start":"2026-06-19T18:08:02.016187Z","end":"2026-06-19T18:08:02.137306Z","steps":["trace[1978748733] 'process raft request' (duration: 120.974524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:08:02.137659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.493974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T18:08:02.137722Z","caller":"traceutil/trace.go:171","msg":"trace[102471044] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2211; }","duration":"145.641227ms","start":"2026-06-19T18:08:01.992069Z","end":"2026-06-19T18:08:02.13771Z","steps":["trace[102471044] 'agreement among raft nodes before linearized reading' (duration: 145.477063ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:08:03.033422Z","caller":"traceutil/trace.go:171","msg":"trace[447015568] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"234.892216ms","start":"2026-06-19T18:08:02.798497Z","end":"2026-06-19T18:08:03.033389Z","steps":["trace[447015568] 'process raft request' (duration: 234.671191ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:08:53.592394Z","caller":"traceutil/trace.go:171","msg":"trace[1278347579] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"311.771167ms","start":"2026-06-19T18:08:53.280588Z","end":"2026-06-19T18:08:53.592359Z","steps":["trace[1278347579] 'process raft request' (duration: 311.564622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:08:53.592676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:08:53.280559Z","time spent":"311.946631ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T18:08:53.615606Z","caller":"traceutil/trace.go:171","msg":"trace[1597640086] linearizableReadLoop","detail":"{readStateIndex:2762; appliedIndex:2760; }","duration":"171.926874ms","start":"2026-06-19T18:08:53.443658Z","end":"2026-06-19T18:08:53.615585Z","steps":["trace[1597640086] 'read index received' (duration: 148.503975ms)","trace[1597640086] 'applied index is now lower than readState.Index' (duration: 23.421799ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:08:53.615687Z","caller":"traceutil/trace.go:171","msg":"trace[1968729328] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"289.557058ms","start":"2026-06-19T18:08:53.326088Z","end":"2026-06-19T18:08:53.615645Z","steps":["trace[1968729328] 'process raft request' (duration: 288.744458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:08:53.615859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.173079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-19T18:08:53.615974Z","caller":"traceutil/trace.go:171","msg":"trace[839827600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2657; }","duration":"172.342973ms","start":"2026-06-19T18:08:53.443603Z","end":"2026-06-19T18:08:53.615946Z","steps":["trace[839827600] 'agreement among raft nodes before linearized reading' (duration: 172.069107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:09:26.656423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.670046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T18:09:26.656663Z","caller":"traceutil/trace.go:171","msg":"trace[1840935072] transaction","detail":"{read_only:false; response_revision:2861; number_of_response:1; }","duration":"151.42455ms","start":"2026-06-19T18:09:26.505215Z","end":"2026-06-19T18:09:26.656639Z","steps":["trace[1840935072] 'process raft request' (duration: 151.384859ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:09:26.65692Z","caller":"traceutil/trace.go:171","msg":"trace[779781802] transaction","detail":"{read_only:false; response_revision:2860; number_of_response:1; }","duration":"224.026965ms","start":"2026-06-19T18:09:26.432885Z","end":"2026-06-19T18:09:26.656912Z","steps":["trace[779781802] 'process raft request' (duration: 223.662627ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:09:26.656966Z","caller":"traceutil/trace.go:171","msg":"trace[1443684991] transaction","detail":"{read_only:false; response_revision:2859; number_of_response:1; }","duration":"224.359454ms","start":"2026-06-19T18:09:26.432521Z","end":"2026-06-19T18:09:26.65688Z","steps":["trace[1443684991] 'process raft request' (duration: 60.132944ms)","trace[1443684991] 'compare' (duration: 163.552414ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:13:17.568245Z","caller":"traceutil/trace.go:171","msg":"trace[1990006440] linearizableReadLoop","detail":"{readStateIndex:5056; appliedIndex:5054; }","duration":"186.361502ms","start":"2026-06-19T18:13:17.381856Z","end":"2026-06-19T18:13:17.568218Z","steps":["trace[1990006440] 'read index received' (duration: 36.711133ms)","trace[1990006440] 'applied index is now lower than readState.Index' (duration: 149.649129ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:13:17.568395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.505426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:13:17.568425Z","caller":"traceutil/trace.go:171","msg":"trace[969610299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4867; }","duration":"186.600448ms","start":"2026-06-19T18:13:17.381816Z","end":"2026-06-19T18:13:17.568416Z","steps":["trace[969610299] 'agreement among raft nodes before linearized reading' (duration: 186.509266ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:17.568571Z","caller":"traceutil/trace.go:171","msg":"trace[1921599133] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"211.80684ms","start":"2026-06-19T18:13:17.356733Z","end":"2026-06-19T18:13:17.56854Z","steps":["trace[1921599133] 'process raft request' (duration: 189.536739ms)","trace[1921599133] 'compare' (duration: 21.80202ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:13:20.743623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.938014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8mr9q\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-06-19T18:13:20.743742Z","caller":"traceutil/trace.go:171","msg":"trace[977526716] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8mr9q; range_end:; response_count:1; response_revision:5037; }","duration":"168.099118ms","start":"2026-06-19T18:13:20.575617Z","end":"2026-06-19T18:13:20.743716Z","steps":["trace[977526716] 'range keys from in-memory index tree' (duration: 167.690007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:21.162389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.375111ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-19T18:13:21.162477Z","caller":"traceutil/trace.go:171","msg":"trace[1406363105] linearizableReadLoop","detail":"{readStateIndex:5234; appliedIndex:5233; }","duration":"107.584996ms","start":"2026-06-19T18:13:21.05488Z","end":"2026-06-19T18:13:21.162465Z","steps":["trace[1406363105] 'read index received' (duration: 33.361µs)","trace[1406363105] 'applied index is now lower than readState.Index' (duration: 107.550755ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:13:21.162614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.724499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-19T18:13:21.16263Z","caller":"traceutil/trace.go:171","msg":"trace[1941574108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5039; }","duration":"107.77884ms","start":"2026-06-19T18:13:21.054844Z","end":"2026-06-19T18:13:21.162623Z","steps":["trace[1941574108] 'agreement among raft nodes before linearized reading' (duration: 107.678988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:21.162725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.175646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-19T18:13:21.162822Z","caller":"traceutil/trace.go:171","msg":"trace[342326181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5039; }","duration":"107.297909ms","start":"2026-06-19T18:13:21.055504Z","end":"2026-06-19T18:13:21.162802Z","steps":["trace[342326181] 'agreement among raft nodes before linearized reading' (duration: 107.063933ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:32.059129Z","caller":"traceutil/trace.go:171","msg":"trace[160141641] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"128.076035ms","start":"2026-06-19T18:13:31.931034Z","end":"2026-06-19T18:13:32.05911Z","steps":["trace[160141641] 'process raft request' (duration: 125.467231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:39.795169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.068926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-19T18:13:39.79518Z","caller":"traceutil/trace.go:171","msg":"trace[211283081] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"137.545535ms","start":"2026-06-19T18:13:39.657603Z","end":"2026-06-19T18:13:39.795148Z","steps":["trace[211283081] 'process raft request' (duration: 71.861828ms)","trace[211283081] 'compare' (duration: 65.551714ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:13:39.795268Z","caller":"traceutil/trace.go:171","msg":"trace[372725938] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5424; }","duration":"161.22634ms","start":"2026-06-19T18:13:39.634024Z","end":"2026-06-19T18:13:39.79525Z","steps":["trace[372725938] 'range keys from in-memory index tree' (duration: 160.864321ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:39.820612Z","caller":"traceutil/trace.go:171","msg":"trace[364734092] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"161.326652ms","start":"2026-06-19T18:13:39.659259Z","end":"2026-06-19T18:13:39.820585Z","steps":["trace[364734092] 'process raft request' (duration: 161.075946ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:51.546732Z","caller":"traceutil/trace.go:171","msg":"trace[1489803959] transaction","detail":"{read_only:false; response_revision:5602; number_of_response:1; }","duration":"203.381232ms","start":"2026-06-19T18:13:51.342935Z","end":"2026-06-19T18:13:51.546317Z","steps":["trace[1489803959] 'process raft request' (duration: 203.190297ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:51.711034Z","caller":"traceutil/trace.go:171","msg":"trace[212695459] linearizableReadLoop","detail":"{readStateIndex:5812; appliedIndex:5810; }","duration":"330.088486ms","start":"2026-06-19T18:13:51.38092Z","end":"2026-06-19T18:13:51.711009Z","steps":["trace[212695459] 'read index received' (duration: 165.265064ms)","trace[212695459] 'applied index is now lower than readState.Index' (duration: 164.822332ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:13:51.711078Z","caller":"traceutil/trace.go:171","msg":"trace[65569859] transaction","detail":"{read_only:false; response_revision:5603; number_of_response:1; }","duration":"367.68329ms","start":"2026-06-19T18:13:51.343357Z","end":"2026-06-19T18:13:51.711041Z","steps":["trace[65569859] 'process raft request' (duration: 367.472475ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:51.711123Z","caller":"traceutil/trace.go:171","msg":"trace[1271279903] transaction","detail":"{read_only:false; response_revision:5604; number_of_response:1; }","duration":"322.413023ms","start":"2026-06-19T18:13:51.388662Z","end":"2026-06-19T18:13:51.711075Z","steps":["trace[1271279903] 'process raft request' (duration: 322.3028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:51.711238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:13:51.343341Z","time spent":"367.812604ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T18:13:51.711303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:13:51.388619Z","time spent":"322.592777ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T18:13:51.711457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.344748ms","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-19T18:13:51.711512Z","caller":"traceutil/trace.go:171","msg":"trace[1809931940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5604; }","duration":"263.40122ms","start":"2026-06-19T18:13:51.448079Z","end":"2026-06-19T18:13:51.71148Z","steps":["trace[1809931940] 'agreement among raft nodes before linearized reading' (duration: 263.265026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:51.711542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.27725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:13:51.711588Z","caller":"traceutil/trace.go:171","msg":"trace[159355296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5604; }","duration":"330.69136ms","start":"2026-06-19T18:13:51.380883Z","end":"2026-06-19T18:13:51.711574Z","steps":["trace[159355296] 'agreement among raft nodes before linearized reading' (duration: 330.257009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:51.711622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:13:51.380869Z","time spent":"330.745482ms","remote":"127.0.0.1:52850","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-19T18:14:52.800462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3005} {"level":"info","ts":"2026-06-19T18:14:52.881602Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3005,"took":"79.563631ms","hash":3996549160,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":31973376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-19T18:14:52.881672Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3996549160,"revision":3005,"compact-revision":-1} {"level":"info","ts":"2026-06-19T18:19:51.402324Z","caller":"traceutil/trace.go:171","msg":"trace[1050617412] transaction","detail":"{read_only:false; response_revision:8298; number_of_response:1; }","duration":"201.323643ms","start":"2026-06-19T18:19:51.200978Z","end":"2026-06-19T18:19:51.402301Z","steps":["trace[1050617412] 'process raft request' (duration: 200.938904ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:19:51.574821Z","caller":"traceutil/trace.go:171","msg":"trace[1164597624] transaction","detail":"{read_only:false; response_revision:8299; number_of_response:1; }","duration":"169.180785ms","start":"2026-06-19T18:19:51.405613Z","end":"2026-06-19T18:19:51.574793Z","steps":["trace[1164597624] 'process raft request' (duration: 166.897631ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:19:51.575022Z","caller":"traceutil/trace.go:171","msg":"trace[1739630969] transaction","detail":"{read_only:false; response_revision:8300; number_of_response:1; }","duration":"168.784427ms","start":"2026-06-19T18:19:51.406198Z","end":"2026-06-19T18:19:51.574982Z","steps":["trace[1739630969] 'process raft request' (duration: 168.54593ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:19:52.806845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6110} {"level":"info","ts":"2026-06-19T18:19:52.901223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6110,"took":"92.360826ms","hash":1121094322,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":23465984,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-19T18:19:52.901293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1121094322,"revision":6110,"compact-revision":3005} {"level":"warn","ts":"2026-06-19T18:20:03.790509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.101555ms","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-19T18:20:03.790601Z","caller":"traceutil/trace.go:171","msg":"trace[883842572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8362; }","duration":"160.256529ms","start":"2026-06-19T18:20:03.630323Z","end":"2026-06-19T18:20:03.79058Z","steps":["trace[883842572] 'range keys from in-memory index tree' (duration: 159.859699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:20:03.790829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.664448ms","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-19T18:20:03.790922Z","caller":"traceutil/trace.go:171","msg":"trace[1517038716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8362; }","duration":"155.76341ms","start":"2026-06-19T18:20:03.635133Z","end":"2026-06-19T18:20:03.790896Z","steps":["trace[1517038716] 'range keys from in-memory index tree' (duration: 155.509224ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:05.231619Z","caller":"traceutil/trace.go:171","msg":"trace[779408955] transaction","detail":"{read_only:false; response_revision:8368; number_of_response:1; }","duration":"153.868874ms","start":"2026-06-19T18:20:05.077726Z","end":"2026-06-19T18:20:05.231595Z","steps":["trace[779408955] 'process raft request' (duration: 153.686429ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:05.253464Z","caller":"traceutil/trace.go:171","msg":"trace[546111951] linearizableReadLoop","detail":"{readStateIndex:8667; appliedIndex:8665; }","duration":"155.275018ms","start":"2026-06-19T18:20:05.098177Z","end":"2026-06-19T18:20:05.253452Z","steps":["trace[546111951] 'read index received' (duration: 133.292516ms)","trace[546111951] 'applied index is now lower than readState.Index' (duration: 21.981662ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:20:05.253528Z","caller":"traceutil/trace.go:171","msg":"trace[1880982191] transaction","detail":"{read_only:false; response_revision:8369; number_of_response:1; }","duration":"175.509078ms","start":"2026-06-19T18:20:05.078011Z","end":"2026-06-19T18:20:05.253521Z","steps":["trace[1880982191] 'process raft request' (duration: 175.383885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:20:05.25368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.493864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-19T18:20:05.253708Z","caller":"traceutil/trace.go:171","msg":"trace[1163155526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8369; }","duration":"155.573935ms","start":"2026-06-19T18:20:05.098127Z","end":"2026-06-19T18:20:05.253701Z","steps":["trace[1163155526] 'agreement among raft nodes before linearized reading' (duration: 155.456912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:20:05.253786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.518782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-19T18:20:05.253883Z","caller":"traceutil/trace.go:171","msg":"trace[2077041393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8369; }","duration":"152.640535ms","start":"2026-06-19T18:20:05.101225Z","end":"2026-06-19T18:20:05.253865Z","steps":["trace[2077041393] 'agreement among raft nodes before linearized reading' (duration: 152.44166ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:32.713806Z","caller":"traceutil/trace.go:171","msg":"trace[80186515] linearizableReadLoop","detail":"{readStateIndex:8877; appliedIndex:8876; }","duration":"332.554081ms","start":"2026-06-19T18:20:32.381213Z","end":"2026-06-19T18:20:32.713767Z","steps":["trace[80186515] 'read index received' (duration: 332.265034ms)","trace[80186515] 'applied index is now lower than readState.Index' (duration: 287.717µs)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:20:32.713935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.714125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:20:32.713966Z","caller":"traceutil/trace.go:171","msg":"trace[1617972455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8571; }","duration":"332.794907ms","start":"2026-06-19T18:20:32.381161Z","end":"2026-06-19T18:20:32.713956Z","steps":["trace[1617972455] 'agreement among raft nodes before linearized reading' (duration: 332.726795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:20:32.714145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:20:32.381147Z","time spent":"332.984981ms","remote":"127.0.0.1:52850","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-19T18:20:32.714132Z","caller":"traceutil/trace.go:171","msg":"trace[1301831798] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"370.907107ms","start":"2026-06-19T18:20:32.343196Z","end":"2026-06-19T18:20:32.714103Z","steps":["trace[1301831798] 'process raft request' (duration: 370.353834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:20:32.714682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:20:32.343171Z","time spent":"371.275996ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T18:20:36.319095Z","caller":"traceutil/trace.go:171","msg":"trace[350814678] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"281.856245ms","start":"2026-06-19T18:20:36.037213Z","end":"2026-06-19T18:20:36.31907Z","steps":["trace[350814678] 'process raft request' (duration: 277.266993ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:36.325206Z","caller":"traceutil/trace.go:171","msg":"trace[1746502134] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"285.227467ms","start":"2026-06-19T18:20:36.039966Z","end":"2026-06-19T18:20:36.325194Z","steps":["trace[1746502134] 'process raft request' (duration: 285.181116ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:36.32524Z","caller":"traceutil/trace.go:171","msg":"trace[1663919302] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"286.19145ms","start":"2026-06-19T18:20:36.039008Z","end":"2026-06-19T18:20:36.3252Z","steps":["trace[1663919302] 'process raft request' (duration: 286.072027ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:36.325363Z","caller":"traceutil/trace.go:171","msg":"trace[58313148] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"279.744293ms","start":"2026-06-19T18:20:36.045607Z","end":"2026-06-19T18:20:36.325352Z","steps":["trace[58313148] 'process raft request' (duration: 279.563768ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:45.016328Z","caller":"traceutil/trace.go:171","msg":"trace[2044834005] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"115.645141ms","start":"2026-06-19T18:20:44.900654Z","end":"2026-06-19T18:20:45.016299Z","steps":["trace[2044834005] 'process raft request' (duration: 115.494137ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:21:00.35259Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ac.wal"} {"level":"info","ts":"2026-06-19T18:21:58.877156Z","caller":"traceutil/trace.go:171","msg":"trace[1184612530] linearizableReadLoop","detail":"{readStateIndex:9743; appliedIndex:9742; }","duration":"175.690625ms","start":"2026-06-19T18:21:58.701434Z","end":"2026-06-19T18:21:58.877125Z","steps":["trace[1184612530] 'read index received' (duration: 175.608543ms)","trace[1184612530] 'applied index is now lower than readState.Index' (duration: 80.542µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:21:58.877204Z","caller":"traceutil/trace.go:171","msg":"trace[575334610] transaction","detail":"{read_only:false; response_revision:9412; number_of_response:1; }","duration":"240.621098ms","start":"2026-06-19T18:21:58.636548Z","end":"2026-06-19T18:21:58.877169Z","steps":["trace[575334610] 'process raft request' (duration: 240.422794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:21:58.877404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.958382ms","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-19T18:21:58.877446Z","caller":"traceutil/trace.go:171","msg":"trace[777807590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9412; }","duration":"176.059375ms","start":"2026-06-19T18:21:58.701377Z","end":"2026-06-19T18:21:58.877436Z","steps":["trace[777807590] 'agreement among raft nodes before linearized reading' (duration: 175.894151ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:21:58.896346Z","caller":"traceutil/trace.go:171","msg":"trace[253822691] transaction","detail":"{read_only:false; response_revision:9413; number_of_response:1; }","duration":"178.980776ms","start":"2026-06-19T18:21:58.717341Z","end":"2026-06-19T18:21:58.896322Z","steps":["trace[253822691] 'process raft request' (duration: 178.799341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:21:58.896438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.425063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-19T18:21:58.896438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.522917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:21:58.89649Z","caller":"traceutil/trace.go:171","msg":"trace[2030536762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9413; }","duration":"183.526925ms","start":"2026-06-19T18:21:58.712951Z","end":"2026-06-19T18:21:58.896478Z","steps":["trace[2030536762] 'agreement among raft nodes before linearized reading' (duration: 183.336031ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:21:58.896512Z","caller":"traceutil/trace.go:171","msg":"trace[1135325425] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:9413; }","duration":"124.608219ms","start":"2026-06-19T18:21:58.77188Z","end":"2026-06-19T18:21:58.896488Z","steps":["trace[1135325425] 'agreement among raft nodes before linearized reading' (duration: 124.475825ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:22:34.97442Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-19T18:22:34.984945Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-19T18:22:34.985055Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-19T18:24:52.813418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8304} {"level":"info","ts":"2026-06-19T18:24:52.892941Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8304,"took":"77.140377ms","hash":3051501066,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":24928256,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-19T18:24:52.893035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3051501066,"revision":8304,"compact-revision":6110} {"level":"info","ts":"2026-06-19T18:27:33.674947Z","caller":"traceutil/trace.go:171","msg":"trace[583244261] linearizableReadLoop","detail":"{readStateIndex:12237; appliedIndex:12236; }","duration":"103.327975ms","start":"2026-06-19T18:27:33.571589Z","end":"2026-06-19T18:27:33.674917Z","steps":["trace[583244261] 'read index received' (duration: 103.1325ms)","trace[583244261] 'applied index is now lower than readState.Index' (duration: 194.225µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:27:33.6751Z","caller":"traceutil/trace.go:171","msg":"trace[778577237] transaction","detail":"{read_only:false; response_revision:11829; number_of_response:1; }","duration":"159.418909ms","start":"2026-06-19T18:27:33.515666Z","end":"2026-06-19T18:27:33.675084Z","steps":["trace[778577237] 'process raft request' (duration: 159.07312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:27:33.675203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.220592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:27:33.675304Z","caller":"traceutil/trace.go:171","msg":"trace[1995201456] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11829; }","duration":"103.356345ms","start":"2026-06-19T18:27:33.571922Z","end":"2026-06-19T18:27:33.675278Z","steps":["trace[1995201456] 'agreement among raft nodes before linearized reading' (duration: 103.220952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:27:33.675576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.5531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T18:27:33.675602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.510384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-06-19T18:27:33.675626Z","caller":"traceutil/trace.go:171","msg":"trace[149419151] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11829; }","duration":"103.630771ms","start":"2026-06-19T18:27:33.571984Z","end":"2026-06-19T18:27:33.675615Z","steps":["trace[149419151] 'agreement among raft nodes before linearized reading' (duration: 103.538299ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:27:33.67564Z","caller":"traceutil/trace.go:171","msg":"trace[1478459919] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11829; }","duration":"102.562495ms","start":"2026-06-19T18:27:33.573063Z","end":"2026-06-19T18:27:33.675626Z","steps":["trace[1478459919] 'agreement among raft nodes before linearized reading' (duration: 102.440242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:27:33.675635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.624149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-19T18:27:33.675725Z","caller":"traceutil/trace.go:171","msg":"trace[2019169670] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11829; }","duration":"100.742981ms","start":"2026-06-19T18:27:33.57496Z","end":"2026-06-19T18:27:33.675703Z","steps":["trace[2019169670] 'agreement among raft nodes before linearized reading' (duration: 100.470195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:27:33.67588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.303968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:27:33.675904Z","caller":"traceutil/trace.go:171","msg":"trace[1910226492] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11829; }","duration":"104.373549ms","start":"2026-06-19T18:27:33.571525Z","end":"2026-06-19T18:27:33.675899Z","steps":["trace[1910226492] 'agreement among raft nodes before linearized reading' (duration: 104.336688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:27:33.675985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.332354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:27:33.676253Z","caller":"traceutil/trace.go:171","msg":"trace[683953052] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11829; }","duration":"103.594769ms","start":"2026-06-19T18:27:33.57257Z","end":"2026-06-19T18:27:33.676165Z","steps":["trace[683953052] 'agreement among raft nodes before linearized reading' (duration: 103.20144ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:27:33.973212Z","caller":"traceutil/trace.go:171","msg":"trace[1550245093] linearizableReadLoop","detail":"{readStateIndex:12238; appliedIndex:12237; }","duration":"207.351515ms","start":"2026-06-19T18:27:33.765832Z","end":"2026-06-19T18:27:33.973184Z","steps":["trace[1550245093] 'read index received' (duration: 187.005119ms)","trace[1550245093] 'applied index is now lower than readState.Index' (duration: 20.345406ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:27:33.973356Z","caller":"traceutil/trace.go:171","msg":"trace[115628049] transaction","detail":"{read_only:false; response_revision:11830; number_of_response:1; }","duration":"257.772512ms","start":"2026-06-19T18:27:33.715563Z","end":"2026-06-19T18:27:33.973336Z","steps":["trace[115628049] 'process raft request' (duration: 237.289233ms)","trace[115628049] 'compare' (duration: 20.207932ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:27:33.973496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.410512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T18:27:33.973501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.655833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-19T18:27:33.973554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.502894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:27:33.973588Z","caller":"traceutil/trace.go:171","msg":"trace[1332969234] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:11830; }","duration":"207.796657ms","start":"2026-06-19T18:27:33.765771Z","end":"2026-06-19T18:27:33.973567Z","steps":["trace[1332969234] 'agreement among raft nodes before linearized reading' (duration: 207.650683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:27:33.973498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.498934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:27:33.973618Z","caller":"traceutil/trace.go:171","msg":"trace[1681979273] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11830; }","duration":"109.611246ms","start":"2026-06-19T18:27:33.863989Z","end":"2026-06-19T18:27:33.9736Z","steps":["trace[1681979273] 'agreement among raft nodes before linearized reading' (duration: 109.504454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:27:33.973642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.660578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8mr9q\" ","response":"range_response_count:1 size:6518"} {"level":"warn","ts":"2026-06-19T18:27:33.973638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.717832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-19T18:27:33.973671Z","caller":"traceutil/trace.go:171","msg":"trace[394209642] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11830; }","duration":"109.71974ms","start":"2026-06-19T18:27:33.863931Z","end":"2026-06-19T18:27:33.973651Z","steps":["trace[394209642] 'agreement among raft nodes before linearized reading' (duration: 109.498854ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:27:33.973672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.534265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:27:33.9737Z","caller":"traceutil/trace.go:171","msg":"trace[1496204905] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11830; }","duration":"107.818543ms","start":"2026-06-19T18:27:33.865866Z","end":"2026-06-19T18:27:33.973685Z","steps":["trace[1496204905] 'agreement among raft nodes before linearized reading' (duration: 107.597688ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:27:33.973691Z","caller":"traceutil/trace.go:171","msg":"trace[394446162] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8mr9q; range_end:; response_count:1; response_revision:11830; }","duration":"109.75703ms","start":"2026-06-19T18:27:33.863918Z","end":"2026-06-19T18:27:33.973675Z","steps":["trace[394446162] 'agreement among raft nodes before linearized reading' (duration: 109.623157ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:27:33.973773Z","caller":"traceutil/trace.go:171","msg":"trace[195281803] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11830; }","duration":"109.610877ms","start":"2026-06-19T18:27:33.864117Z","end":"2026-06-19T18:27:33.973727Z","steps":["trace[195281803] 'agreement among raft nodes before linearized reading' (duration: 109.480143ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:27:33.973578Z","caller":"traceutil/trace.go:171","msg":"trace[1399423966] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11830; }","duration":"109.501794ms","start":"2026-06-19T18:27:33.864057Z","end":"2026-06-19T18:27:33.973559Z","steps":["trace[1399423966] 'agreement among raft nodes before linearized reading' (duration: 109.383262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:27:34.795378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.706561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-backup-54c56f7b4d-v8zt8.18ba8ecf264633d7\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-06-19T18:27:34.795452Z","caller":"traceutil/trace.go:171","msg":"trace[294059916] range","detail":"{range_begin:/registry/events/openstack/cinder-backup-54c56f7b4d-v8zt8.18ba8ecf264633d7; range_end:; response_count:1; response_revision:11834; }","duration":"151.840124ms","start":"2026-06-19T18:27:34.643594Z","end":"2026-06-19T18:27:34.795434Z","steps":["trace[294059916] 'range keys from in-memory index tree' (duration: 151.471975ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:27:34.880355Z","caller":"traceutil/trace.go:171","msg":"trace[1072981446] transaction","detail":"{read_only:false; response_revision:11835; number_of_response:1; }","duration":"160.418183ms","start":"2026-06-19T18:27:34.719904Z","end":"2026-06-19T18:27:34.880322Z","steps":["trace[1072981446] 'process raft request' (duration: 160.206168ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:27:34.936217Z","caller":"traceutil/trace.go:171","msg":"trace[1529518349] transaction","detail":"{read_only:false; response_revision:11836; number_of_response:1; }","duration":"138.810567ms","start":"2026-06-19T18:27:34.797379Z","end":"2026-06-19T18:27:34.936189Z","steps":["trace[1529518349] 'process raft request' (duration: 108.449778ms)","trace[1529518349] 'compare' (duration: 30.024831ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:27:34.936276Z","caller":"traceutil/trace.go:171","msg":"trace[1761338247] transaction","detail":"{read_only:false; response_revision:11837; number_of_response:1; }","duration":"108.962221ms","start":"2026-06-19T18:27:34.827287Z","end":"2026-06-19T18:27:34.93625Z","steps":["trace[1761338247] 'process raft request' (duration: 108.792247ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:27:34.936429Z","caller":"traceutil/trace.go:171","msg":"trace[1472214839] transaction","detail":"{read_only:false; response_revision:11838; number_of_response:1; }","duration":"108.358216ms","start":"2026-06-19T18:27:34.828056Z","end":"2026-06-19T18:27:34.936414Z","steps":["trace[1472214839] 'process raft request' (duration: 108.144381ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:29:52.820884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10529} {"level":"info","ts":"2026-06-19T18:29:52.896741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10529,"took":"73.470607ms","hash":2400052536,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":26419200,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-19T18:29:52.896896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2400052536,"revision":10529,"compact-revision":8304} {"level":"info","ts":"2026-06-19T18:34:12.319877Z","caller":"traceutil/trace.go:171","msg":"trace[1049597414] transaction","detail":"{read_only:false; response_revision:14493; number_of_response:1; }","duration":"103.014874ms","start":"2026-06-19T18:34:12.216834Z","end":"2026-06-19T18:34:12.319849Z","steps":["trace[1049597414] 'process raft request' (duration: 102.82419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:34:12.848189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122523113249496,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-19T18:34:13.231778Z","caller":"traceutil/trace.go:171","msg":"trace[1289189808] linearizableReadLoop","detail":"{readStateIndex:14998; appliedIndex:14996; }","duration":"883.735344ms","start":"2026-06-19T18:34:12.348017Z","end":"2026-06-19T18:34:13.231753Z","steps":["trace[1289189808] 'read index received' (duration: 883.56205ms)","trace[1289189808] 'applied index is now lower than readState.Index' (duration: 172.454µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:34:13.231816Z","caller":"traceutil/trace.go:171","msg":"trace[930168851] transaction","detail":"{read_only:false; response_revision:14494; number_of_response:1; }","duration":"1.012023562s","start":"2026-06-19T18:34:12.219761Z","end":"2026-06-19T18:34:13.231784Z","steps":["trace[930168851] 'process raft request' (duration: 1.011814217s)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:34:13.231826Z","caller":"traceutil/trace.go:171","msg":"trace[1086996091] transaction","detail":"{read_only:false; response_revision:14495; number_of_response:1; }","duration":"1.011761186s","start":"2026-06-19T18:34:12.220038Z","end":"2026-06-19T18:34:13.231799Z","steps":["trace[1086996091] 'process raft request' (duration: 1.011671894s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:34:13.232012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:34:12.220029Z","time spent":"1.011904149s","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T18:34:13.232018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.98225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-19T18:34:13.232016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:34:12.21974Z","time spent":"1.012140765s","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T18:34:13.232057Z","caller":"traceutil/trace.go:171","msg":"trace[1210967688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14495; }","duration":"884.080482ms","start":"2026-06-19T18:34:12.347964Z","end":"2026-06-19T18:34:13.232044Z","steps":["trace[1210967688] 'agreement among raft nodes before linearized reading' (duration: 883.920478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:34:13.232097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:34:12.347941Z","time spent":"884.147954ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-19T18:34:13.472838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.513353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T18:34:13.473078Z","caller":"traceutil/trace.go:171","msg":"trace[1611583701] transaction","detail":"{read_only:false; response_revision:14498; number_of_response:1; }","duration":"237.068263ms","start":"2026-06-19T18:34:13.23599Z","end":"2026-06-19T18:34:13.473058Z","steps":["trace[1611583701] 'process raft request' (duration: 237.024242ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:34:13.473091Z","caller":"traceutil/trace.go:171","msg":"trace[1332510662] transaction","detail":"{read_only:false; response_revision:14497; number_of_response:1; }","duration":"265.73842ms","start":"2026-06-19T18:34:13.207322Z","end":"2026-06-19T18:34:13.473061Z","steps":["trace[1332510662] 'process raft request' (duration: 265.618847ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:34:13.473125Z","caller":"traceutil/trace.go:171","msg":"trace[819213471] linearizableReadLoop","detail":"{readStateIndex:15000; appliedIndex:14998; }","duration":"241.248214ms","start":"2026-06-19T18:34:13.23186Z","end":"2026-06-19T18:34:13.473108Z","steps":["trace[819213471] 'read index received' (duration: 110.310791ms)","trace[819213471] 'applied index is now lower than readState.Index' (duration: 130.935703ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:34:13.473142Z","caller":"traceutil/trace.go:171","msg":"trace[1106595912] transaction","detail":"{read_only:false; response_revision:14496; number_of_response:1; }","duration":"497.459964ms","start":"2026-06-19T18:34:12.975642Z","end":"2026-06-19T18:34:13.473102Z","steps":["trace[1106595912] 'process raft request' (duration: 366.558231ms)","trace[1106595912] 'compare' (duration: 130.333949ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:34:13.473247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.027106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8mr9q\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-06-19T18:34:13.473291Z","caller":"traceutil/trace.go:171","msg":"trace[1668771645] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8mr9q; range_end:; response_count:1; response_revision:14498; }","duration":"426.109228ms","start":"2026-06-19T18:34:13.047172Z","end":"2026-06-19T18:34:13.473281Z","steps":["trace[1668771645] 'agreement among raft nodes before linearized reading' (duration: 425.951454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:34:13.473307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125273386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-19T18:34:13.47332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:34:13.047155Z","time spent":"426.15935ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6541,"request content":"key:\"/registry/pods/openstack/keepalived-8mr9q\" "} {"level":"warn","ts":"2026-06-19T18:34:13.473355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.880027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T18:34:13.47336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01524295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-xb7fh\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-06-19T18:34:13.473393Z","caller":"traceutil/trace.go:171","msg":"trace[1762100918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14498; }","duration":"1.125348867s","start":"2026-06-19T18:34:12.348Z","end":"2026-06-19T18:34:13.473349Z","steps":["trace[1762100918] 'agreement among raft nodes before linearized reading' (duration: 1.125228454s)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:34:13.473411Z","caller":"traceutil/trace.go:171","msg":"trace[740728832] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:14498; }","duration":"340.99415ms","start":"2026-06-19T18:34:13.132403Z","end":"2026-06-19T18:34:13.473397Z","steps":["trace[740728832] 'agreement among raft nodes before linearized reading' (duration: 340.864937ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:34:13.473427Z","caller":"traceutil/trace.go:171","msg":"trace[938409789] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-xb7fh; range_end:; response_count:1; response_revision:14498; }","duration":"1.015358823s","start":"2026-06-19T18:34:12.458049Z","end":"2026-06-19T18:34:13.473408Z","steps":["trace[938409789] 'agreement among raft nodes before linearized reading' (duration: 1.015113827s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:34:13.47344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:34:12.975614Z","time spent":"497.75993ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T18:34:13.473471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:34:12.458025Z","time spent":"1.015438485s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12383,"request content":"key:\"/registry/pods/openstack/openvswitch-xb7fh\" "} {"level":"warn","ts":"2026-06-19T18:34:13.473469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:34:13.13239Z","time spent":"341.068281ms","remote":"127.0.0.1:33286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-06-19T18:34:13.473453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:34:12.347988Z","time spent":"1.12545783s","remote":"127.0.0.1:53082","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-19T18:34:13.473368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092869598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:34:13.473572Z","caller":"traceutil/trace.go:171","msg":"trace[307490972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14498; }","duration":"1.093160375s","start":"2026-06-19T18:34:12.380399Z","end":"2026-06-19T18:34:13.473559Z","steps":["trace[307490972] 'agreement among raft nodes before linearized reading' (duration: 1.092820637s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:34:13.47362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:34:12.380373Z","time spent":"1.093234176s","remote":"127.0.0.1:52850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-19T18:34:13.496733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.072853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:34:13.49685Z","caller":"traceutil/trace.go:171","msg":"trace[1139844297] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:14499; }","duration":"200.231707ms","start":"2026-06-19T18:34:13.296593Z","end":"2026-06-19T18:34:13.496824Z","steps":["trace[1139844297] 'agreement among raft nodes before linearized reading' (duration: 200.041403ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:34:52.827257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12773} {"level":"info","ts":"2026-06-19T18:34:52.906065Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12773,"took":"75.978827ms","hash":3169813276,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":29437952,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-19T18:34:52.906141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3169813276,"revision":12773,"compact-revision":10529} {"level":"warn","ts":"2026-06-19T18:35:46.483952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.536093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:35:46.484113Z","caller":"traceutil/trace.go:171","msg":"trace[1737758518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15671; }","duration":"102.768798ms","start":"2026-06-19T18:35:46.381313Z","end":"2026-06-19T18:35:46.484082Z","steps":["trace[1737758518] 'range keys from in-memory index tree' (duration: 102.43676ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:39:52.834079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15054} {"level":"info","ts":"2026-06-19T18:39:52.937202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15054,"took":"99.005887ms","hash":2174707953,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":31047680,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-19T18:39:52.937262Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2174707953,"revision":15054,"compact-revision":12773} {"level":"warn","ts":"2026-06-19T18:41:46.488888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.344911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-19T18:41:46.48896Z","caller":"traceutil/trace.go:171","msg":"trace[380317455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18292; }","duration":"105.433064ms","start":"2026-06-19T18:41:46.383506Z","end":"2026-06-19T18:41:46.488939Z","steps":["trace[380317455] 'range keys from in-memory index tree' (duration: 105.304051ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:41:46.997742Z","caller":"traceutil/trace.go:171","msg":"trace[1362433392] linearizableReadLoop","detail":"{readStateIndex:18919; appliedIndex:18918; }","duration":"120.767975ms","start":"2026-06-19T18:41:46.876956Z","end":"2026-06-19T18:41:46.997724Z","steps":["trace[1362433392] 'read index received' (duration: 120.039288ms)","trace[1362433392] 'applied index is now lower than readState.Index' (duration: 728.007µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:41:46.997917Z","caller":"traceutil/trace.go:171","msg":"trace[1161539585] transaction","detail":"{read_only:false; response_revision:18294; number_of_response:1; }","duration":"219.017014ms","start":"2026-06-19T18:41:46.778891Z","end":"2026-06-19T18:41:46.997908Z","steps":["trace[1161539585] 'process raft request' (duration: 218.166014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:41:46.998173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.204266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4ftlf\" ","response":"range_response_count:1 size:20736"} {"level":"info","ts":"2026-06-19T18:41:46.998202Z","caller":"traceutil/trace.go:171","msg":"trace[582077407] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-4ftlf; range_end:; response_count:1; response_revision:18294; }","duration":"121.272687ms","start":"2026-06-19T18:41:46.876922Z","end":"2026-06-19T18:41:46.998195Z","steps":["trace[582077407] 'agreement among raft nodes before linearized reading' (duration: 121.122073ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:41:47.771693Z","caller":"traceutil/trace.go:171","msg":"trace[120992333] linearizableReadLoop","detail":"{readStateIndex:18922; appliedIndex:18921; }","duration":"199.038548ms","start":"2026-06-19T18:41:47.572634Z","end":"2026-06-19T18:41:47.771672Z","steps":["trace[120992333] 'read index received' (duration: 198.867984ms)","trace[120992333] 'applied index is now lower than readState.Index' (duration: 169.694µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:41:47.771922Z","caller":"traceutil/trace.go:171","msg":"trace[806841227] transaction","detail":"{read_only:false; response_revision:18297; number_of_response:1; }","duration":"200.681338ms","start":"2026-06-19T18:41:47.571229Z","end":"2026-06-19T18:41:47.77191Z","steps":["trace[806841227] 'process raft request' (duration: 200.32354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:41:47.772175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.536139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-19T18:41:47.772214Z","caller":"traceutil/trace.go:171","msg":"trace[1411355150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18297; }","duration":"199.625161ms","start":"2026-06-19T18:41:47.572578Z","end":"2026-06-19T18:41:47.772203Z","steps":["trace[1411355150] 'agreement among raft nodes before linearized reading' (duration: 199.500638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:41:47.774212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.872238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-19T18:41:47.77469Z","caller":"traceutil/trace.go:171","msg":"trace[778491289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18297; }","duration":"131.430841ms","start":"2026-06-19T18:41:47.643239Z","end":"2026-06-19T18:41:47.77467Z","steps":["trace[778491289] 'agreement among raft nodes before linearized reading' (duration: 130.709912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:41:47.779509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.292107ms","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-19T18:41:47.779544Z","caller":"traceutil/trace.go:171","msg":"trace[1174782398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18297; }","duration":"138.363569ms","start":"2026-06-19T18:41:47.641172Z","end":"2026-06-19T18:41:47.779535Z","steps":["trace[1174782398] 'agreement among raft nodes before linearized reading' (duration: 131.155224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:41:47.970791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.381457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-19T18:41:47.970867Z","caller":"traceutil/trace.go:171","msg":"trace[633724765] transaction","detail":"{read_only:false; response_revision:18298; number_of_response:1; }","duration":"193.692517ms","start":"2026-06-19T18:41:47.777162Z","end":"2026-06-19T18:41:47.970854Z","steps":["trace[633724765] 'process raft request' (duration: 65.185968ms)","trace[633724765] 'compare' (duration: 128.10109ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:41:47.985254Z","caller":"traceutil/trace.go:171","msg":"trace[870949790] linearizableReadLoop","detail":"{readStateIndex:18925; appliedIndex:18923; }","duration":"139.763584ms","start":"2026-06-19T18:41:47.845458Z","end":"2026-06-19T18:41:47.985222Z","steps":["trace[870949790] 'read index received' (duration: 139.525128ms)","trace[870949790] 'applied index is now lower than readState.Index' (duration: 237.326µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:41:47.98559Z","caller":"traceutil/trace.go:171","msg":"trace[2141408036] transaction","detail":"{read_only:false; response_revision:18299; number_of_response:1; }","duration":"206.144761ms","start":"2026-06-19T18:41:47.779431Z","end":"2026-06-19T18:41:47.985576Z","steps":["trace[2141408036] 'process raft request' (duration: 205.615648ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:41:47.985802Z","caller":"traceutil/trace.go:171","msg":"trace[844835191] transaction","detail":"{read_only:false; response_revision:18300; number_of_response:1; }","duration":"202.953863ms","start":"2026-06-19T18:41:47.782837Z","end":"2026-06-19T18:41:47.985791Z","steps":["trace[844835191] 'process raft request' (duration: 202.340708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:41:47.98603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.554173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pxrpt\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-06-19T18:41:47.986073Z","caller":"traceutil/trace.go:171","msg":"trace[412992036] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pxrpt; range_end:; response_count:1; response_revision:18300; }","duration":"140.629515ms","start":"2026-06-19T18:41:47.845432Z","end":"2026-06-19T18:41:47.986061Z","steps":["trace[412992036] 'agreement among raft nodes before linearized reading' (duration: 140.488032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:41:47.994085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.808067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-5k49b\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-06-19T18:41:47.994166Z","caller":"traceutil/trace.go:171","msg":"trace[552423975] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-5k49b; range_end:; response_count:1; response_revision:18301; }","duration":"112.92007ms","start":"2026-06-19T18:41:47.881229Z","end":"2026-06-19T18:41:47.994149Z","steps":["trace[552423975] 'agreement among raft nodes before linearized reading' (duration: 112.778906ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:44:47.663802Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-19T18:44:47.668631Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-19T18:44:47.668714Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-19T18:44:52.842142Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17526} {"level":"info","ts":"2026-06-19T18:44:52.934514Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17526,"took":"89.981962ms","hash":2291330560,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":29708288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-19T18:44:52.934567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2291330560,"revision":17526,"compact-revision":15054}