{"level":"warn","ts":"2026-05-12T15:10:09.372789Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T15:10:09.372888Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-12T15:10:09.372956Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T15:10:09.372966Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-12T15:10:09.372984Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T15:10:09.373598Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-05-12T15:10:09.373674Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-12T15:10:09.428855Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.329372ms"} {"level":"info","ts":"2026-05-12T15:10:09.439008Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-05-12T15:10:09.439192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T15:10:09.43926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-05-12T15:10:09.43931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T15:10:09.439324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-05-12T15:10:09.439418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-05-12T15:10:09.444561Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T15:10:09.445962Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T15:10:09.447179Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-12T15:10:09.449258Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T15:10:09.449448Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T15:10:09.449544Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T15:10:09.449668Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T15:10:09.44969Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T15:10:09.450962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-05-12T15:10:09.451544Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-12T15:10:09.453855Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T15:10:09.453998Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-12T15:10:09.45407Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-12T15:10:09.454181Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T15:10:09.454248Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T15:10:09.739771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T15:10:09.739915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T15:10:09.739992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-05-12T15:10:09.740013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-05-12T15:10:09.740041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-12T15:10:09.740055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-05-12T15:10:09.740065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-12T15:10:09.741448Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T15:10:09.742119Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T15:10:09.742101Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T15:10:09.742239Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T15:10:09.742506Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T15:10:09.742522Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T15:10:09.742683Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T15:10:09.742701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T15:10:09.742743Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T15:10:09.744406Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-05-12T15:10:09.745849Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T15:10:19.259344Z","caller":"traceutil/trace.go:171","msg":"trace[2131142275] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"426.068252ms","start":"2026-05-12T15:10:18.833247Z","end":"2026-05-12T15:10:19.259315Z","steps":["trace[2131142275] 'process raft request' (duration: 425.835227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:10:19.260039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:18.833233Z","time spent":"426.216275ms","remote":"127.0.0.1:42310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:10:19.459364Z","caller":"traceutil/trace.go:171","msg":"trace[1247927323] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"625.609179ms","start":"2026-05-12T15:10:18.833727Z","end":"2026-05-12T15:10:19.459336Z","steps":["trace[1247927323] 'process raft request' (duration: 625.403225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:10:19.459539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:18.833709Z","time spent":"625.749522ms","remote":"127.0.0.1:42310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:10:19.459643Z","caller":"traceutil/trace.go:171","msg":"trace[809929168] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"625.444826ms","start":"2026-05-12T15:10:18.834177Z","end":"2026-05-12T15:10:19.459622Z","steps":["trace[809929168] 'process raft request' (duration: 625.120438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:10:19.459732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:18.834146Z","time spent":"625.543619ms","remote":"127.0.0.1:42310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:10:19.459543Z","caller":"traceutil/trace.go:171","msg":"trace[1139306054] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:312; }","duration":"585.760751ms","start":"2026-05-12T15:10:18.873761Z","end":"2026-05-12T15:10:19.459522Z","steps":["trace[1139306054] 'read index received' (duration: 385.289764ms)","trace[1139306054] 'applied index is now lower than readState.Index' (duration: 200.469617ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:10:19.459726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.930466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-12T15:10:19.459888Z","caller":"traceutil/trace.go:171","msg":"trace[634382721] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:301; }","duration":"586.164321ms","start":"2026-05-12T15:10:18.873698Z","end":"2026-05-12T15:10:19.459862Z","steps":["trace[634382721] 'agreement among raft nodes before linearized reading' (duration: 585.883434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:10:19.45995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:18.873671Z","time spent":"586.266913ms","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-12T15:10:19.46Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.170186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-05-12T15:10:19.459996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.571573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-12T15:10:19.460044Z","caller":"traceutil/trace.go:171","msg":"trace[234151012] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:301; }","duration":"186.245048ms","start":"2026-05-12T15:10:19.273787Z","end":"2026-05-12T15:10:19.460032Z","steps":["trace[234151012] 'agreement among raft nodes before linearized reading' (duration: 186.152506ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:10:19.460058Z","caller":"traceutil/trace.go:171","msg":"trace[727765642] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:301; }","duration":"336.714716ms","start":"2026-05-12T15:10:19.123326Z","end":"2026-05-12T15:10:19.460041Z","steps":["trace[727765642] 'agreement among raft nodes before linearized reading' (duration: 336.578493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:10:19.460058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.780774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-05-12T15:10:19.460103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:19.123299Z","time spent":"336.790818ms","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-05-12T15:10:19.460146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.260103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T15:10:19.460155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.748033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-12T15:10:19.460191Z","caller":"traceutil/trace.go:171","msg":"trace[1640224814] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:301; }","duration":"485.782583ms","start":"2026-05-12T15:10:18.974399Z","end":"2026-05-12T15:10:19.460182Z","steps":["trace[1640224814] 'agreement among raft nodes before linearized reading' (duration: 485.727402ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:10:19.4602Z","caller":"traceutil/trace.go:171","msg":"trace[172040461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:301; }","duration":"485.331274ms","start":"2026-05-12T15:10:18.974846Z","end":"2026-05-12T15:10:19.460178Z","steps":["trace[172040461] 'agreement among raft nodes before linearized reading' (duration: 485.264793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:10:19.460212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.633922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-12T15:10:19.460206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.689978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-12T15:10:19.460251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:18.974824Z","time spent":"485.418066ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T15:10:19.460245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.728466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-12T15:10:19.460284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.433011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-12T15:10:19.460335Z","caller":"traceutil/trace.go:171","msg":"trace[2009720999] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:301; }","duration":"286.520653ms","start":"2026-05-12T15:10:19.173806Z","end":"2026-05-12T15:10:19.460327Z","steps":["trace[2009720999] 'agreement among raft nodes before linearized reading' (duration: 286.453611ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:10:19.460342Z","caller":"traceutil/trace.go:171","msg":"trace[538646196] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:301; }","duration":"235.868589ms","start":"2026-05-12T15:10:19.224457Z","end":"2026-05-12T15:10:19.460326Z","steps":["trace[538646196] 'agreement among raft nodes before linearized reading' (duration: 235.742776ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:10:19.460367Z","caller":"traceutil/trace.go:171","msg":"trace[1078040023] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:301; }","duration":"136.819071ms","start":"2026-05-12T15:10:19.323456Z","end":"2026-05-12T15:10:19.460275Z","steps":["trace[1078040023] 'agreement among raft nodes before linearized reading' (duration: 136.639398ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:10:19.460142Z","caller":"traceutil/trace.go:171","msg":"trace[1921078603] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:301; }","duration":"435.904887ms","start":"2026-05-12T15:10:19.024215Z","end":"2026-05-12T15:10:19.46012Z","steps":["trace[1921078603] 'agreement among raft nodes before linearized reading' (duration: 435.735193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:10:19.460457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:19.024186Z","time spent":"436.257064ms","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-05-12T15:10:19.460269Z","caller":"traceutil/trace.go:171","msg":"trace[1835158284] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:301; }","duration":"385.731354ms","start":"2026-05-12T15:10:19.074526Z","end":"2026-05-12T15:10:19.460257Z","steps":["trace[1835158284] 'agreement among raft nodes before linearized reading' (duration: 385.643702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:10:19.460511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:19.074502Z","time spent":"386.001069ms","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-12T15:10:19.460224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:18.974311Z","time spent":"485.906396ms","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-05-12T15:10:19.460058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.753303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-12T15:10:19.460618Z","caller":"traceutil/trace.go:171","msg":"trace[938548782] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:301; }","duration":"536.403577ms","start":"2026-05-12T15:10:18.924206Z","end":"2026-05-12T15:10:19.460609Z","steps":["trace[938548782] 'agreement among raft nodes before linearized reading' (duration: 535.686261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:10:19.460653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:18.924181Z","time spent":"536.464388ms","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-05-12T15:10:40.822604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.026378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T15:10:40.822741Z","caller":"traceutil/trace.go:171","msg":"trace[18899018] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:471; }","duration":"122.218732ms","start":"2026-05-12T15:10:40.700491Z","end":"2026-05-12T15:10:40.82271Z","steps":["trace[18899018] 'range keys from in-memory index tree' (duration: 121.943556ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:10:47.107257Z","caller":"traceutil/trace.go:171","msg":"trace[817595711] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"159.340481ms","start":"2026-05-12T15:10:46.947893Z","end":"2026-05-12T15:10:47.107234Z","steps":["trace[817595711] 'process raft request' (duration: 159.199278ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:10:47.419211Z","caller":"traceutil/trace.go:171","msg":"trace[1893151116] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"175.187707ms","start":"2026-05-12T15:10:47.243988Z","end":"2026-05-12T15:10:47.419176Z","steps":["trace[1893151116] 'process raft request' (duration: 174.580664ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:10:47.613374Z","caller":"traceutil/trace.go:171","msg":"trace[1978277095] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"128.270943ms","start":"2026-05-12T15:10:47.485077Z","end":"2026-05-12T15:10:47.613348Z","steps":["trace[1978277095] 'process raft request' (duration: 57.447941ms)","trace[1978277095] 'compare' (duration: 70.660419ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:10:48.166197Z","caller":"traceutil/trace.go:171","msg":"trace[151565717] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"409.137273ms","start":"2026-05-12T15:10:47.757029Z","end":"2026-05-12T15:10:48.166166Z","steps":["trace[151565717] 'process raft request' (duration: 408.96654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:10:48.166406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:10:47.757012Z","time spent":"409.284236ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:10:48.188164Z","caller":"traceutil/trace.go:171","msg":"trace[597765482] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"152.978863ms","start":"2026-05-12T15:10:48.035156Z","end":"2026-05-12T15:10:48.188135Z","steps":["trace[597765482] 'process raft request' (duration: 152.455391ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:11:03.58137Z","caller":"traceutil/trace.go:171","msg":"trace[598713121] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"111.89381ms","start":"2026-05-12T15:11:03.469452Z","end":"2026-05-12T15:11:03.581346Z","steps":["trace[598713121] 'process raft request' (duration: 111.713195ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:11:37.45176Z","caller":"traceutil/trace.go:171","msg":"trace[732963428] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"179.849686ms","start":"2026-05-12T15:11:37.271884Z","end":"2026-05-12T15:11:37.451733Z","steps":["trace[732963428] 'process raft request' (duration: 179.601851ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:11:53.378725Z","caller":"traceutil/trace.go:171","msg":"trace[2071841324] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"100.693504ms","start":"2026-05-12T15:11:53.277998Z","end":"2026-05-12T15:11:53.378691Z","steps":["trace[2071841324] 'process raft request' (duration: 96.60421ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:12:26.459882Z","caller":"traceutil/trace.go:171","msg":"trace[1130450742] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"203.869964ms","start":"2026-05-12T15:12:26.255982Z","end":"2026-05-12T15:12:26.459852Z","steps":["trace[1130450742] 'read index received' (duration: 203.636858ms)","trace[1130450742] 'applied index is now lower than readState.Index' (duration: 231.826µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:12:26.460019Z","caller":"traceutil/trace.go:171","msg":"trace[1900529775] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"233.441601ms","start":"2026-05-12T15:12:26.226541Z","end":"2026-05-12T15:12:26.459983Z","steps":["trace[1900529775] 'process raft request' (duration: 233.166374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:12:26.460268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.289964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-12T15:12:26.460405Z","caller":"traceutil/trace.go:171","msg":"trace[1981509248] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1846; }","duration":"204.475008ms","start":"2026-05-12T15:12:26.255902Z","end":"2026-05-12T15:12:26.460377Z","steps":["trace[1981509248] 'agreement among raft nodes before linearized reading' (duration: 204.11263ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:12:41.088251Z","caller":"traceutil/trace.go:171","msg":"trace[1151984560] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"134.439412ms","start":"2026-05-12T15:12:40.95378Z","end":"2026-05-12T15:12:41.088219Z","steps":["trace[1151984560] 'process raft request' (duration: 133.8906ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:13:14.505883Z","caller":"traceutil/trace.go:171","msg":"trace[499769612] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"160.334644ms","start":"2026-05-12T15:13:14.34552Z","end":"2026-05-12T15:13:14.505854Z","steps":["trace[499769612] 'process raft request' (duration: 160.174581ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:13:20.698553Z","caller":"traceutil/trace.go:171","msg":"trace[1728548743] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"162.730511ms","start":"2026-05-12T15:13:20.535795Z","end":"2026-05-12T15:13:20.698526Z","steps":["trace[1728548743] 'process raft request' (duration: 162.576848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:13:20.897482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.33936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T15:13:20.897507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.452384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T15:13:20.897581Z","caller":"traceutil/trace.go:171","msg":"trace[1996043318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2189; }","duration":"153.539267ms","start":"2026-05-12T15:13:20.744018Z","end":"2026-05-12T15:13:20.897557Z","steps":["trace[1996043318] 'range keys from in-memory index tree' (duration: 153.2463ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:13:20.897565Z","caller":"traceutil/trace.go:171","msg":"trace[1628002650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2189; }","duration":"164.486862ms","start":"2026-05-12T15:13:20.733059Z","end":"2026-05-12T15:13:20.897546Z","steps":["trace[1628002650] 'range keys from in-memory index tree' (duration: 164.252877ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:14:29.62637Z","caller":"traceutil/trace.go:171","msg":"trace[1664639909] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"153.145051ms","start":"2026-05-12T15:14:29.47317Z","end":"2026-05-12T15:14:29.626315Z","steps":["trace[1664639909] 'process raft request' (duration: 141.931556ms)","trace[1664639909] 'compare' (duration: 10.98775ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:14:29.899605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.750213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T15:14:29.899709Z","caller":"traceutil/trace.go:171","msg":"trace[1184965650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2763; }","duration":"166.914228ms","start":"2026-05-12T15:14:29.732772Z","end":"2026-05-12T15:14:29.899686Z","steps":["trace[1184965650] 'range keys from in-memory index tree' (duration: 166.65502ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:14:30.038834Z","caller":"traceutil/trace.go:171","msg":"trace[1767343045] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"134.183913ms","start":"2026-05-12T15:14:29.904612Z","end":"2026-05-12T15:14:30.038796Z","steps":["trace[1767343045] 'process raft request' (duration: 133.865986ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:14:30.119386Z","caller":"traceutil/trace.go:171","msg":"trace[104973495] transaction","detail":"{read_only:false; response_revision:2765; number_of_response:1; }","duration":"127.70389ms","start":"2026-05-12T15:14:29.991608Z","end":"2026-05-12T15:14:30.119312Z","steps":["trace[104973495] 'process raft request' (duration: 127.461755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:17:00.23967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.713443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T15:17:00.240648Z","caller":"traceutil/trace.go:171","msg":"trace[632894187] transaction","detail":"{read_only:false; response_revision:3755; number_of_response:1; }","duration":"208.517539ms","start":"2026-05-12T15:17:00.032064Z","end":"2026-05-12T15:17:00.240581Z","steps":["trace[632894187] 'process raft request' (duration: 76.635979ms)","trace[632894187] 'compare' (duration: 130.59254ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:18:25.842435Z","caller":"traceutil/trace.go:171","msg":"trace[514498827] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"130.216947ms","start":"2026-05-12T15:18:25.712175Z","end":"2026-05-12T15:18:25.842392Z","steps":["trace[514498827] 'process raft request' (duration: 97.830357ms)","trace[514498827] 'compare' (duration: 32.136274ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:18:25.842495Z","caller":"traceutil/trace.go:171","msg":"trace[851763106] linearizableReadLoop","detail":"{readStateIndex:5142; appliedIndex:5140; }","duration":"122.165655ms","start":"2026-05-12T15:18:25.720303Z","end":"2026-05-12T15:18:25.842469Z","steps":["trace[851763106] 'read index received' (duration: 2.97286ms)","trace[851763106] 'applied index is now lower than readState.Index' (duration: 119.191795ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:18:25.842633Z","caller":"traceutil/trace.go:171","msg":"trace[1365512763] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"120.724152ms","start":"2026-05-12T15:18:25.721897Z","end":"2026-05-12T15:18:25.842621Z","steps":["trace[1365512763] 'process raft request' (duration: 120.428495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:18:25.842719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.386452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T15:18:25.842782Z","caller":"traceutil/trace.go:171","msg":"trace[1153956175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4953; }","duration":"109.493645ms","start":"2026-05-12T15:18:25.733273Z","end":"2026-05-12T15:18:25.842766Z","steps":["trace[1153956175] 'agreement among raft nodes before linearized reading' (duration: 109.392282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:18:25.842954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.630118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-12T15:18:25.843051Z","caller":"traceutil/trace.go:171","msg":"trace[1393719623] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4953; }","duration":"122.75841ms","start":"2026-05-12T15:18:25.720273Z","end":"2026-05-12T15:18:25.843032Z","steps":["trace[1393719623] 'agreement among raft nodes before linearized reading' (duration: 122.501724ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:18:37.105467Z","caller":"traceutil/trace.go:171","msg":"trace[1495803094] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"101.553176ms","start":"2026-05-12T15:18:37.003887Z","end":"2026-05-12T15:18:37.10544Z","steps":["trace[1495803094] 'process raft request' (duration: 101.371901ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:18:37.144177Z","caller":"traceutil/trace.go:171","msg":"trace[1549667010] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"135.298339ms","start":"2026-05-12T15:18:37.008822Z","end":"2026-05-12T15:18:37.14412Z","steps":["trace[1549667010] 'process raft request' (duration: 134.966341ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:18:37.14423Z","caller":"traceutil/trace.go:171","msg":"trace[1755614846] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"112.815134ms","start":"2026-05-12T15:18:37.031375Z","end":"2026-05-12T15:18:37.144191Z","steps":["trace[1755614846] 'process raft request' (duration: 112.65078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:18:37.493658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.827193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T15:18:37.49377Z","caller":"traceutil/trace.go:171","msg":"trace[1225166674] linearizableReadLoop","detail":"{readStateIndex:5352; appliedIndex:5351; }","duration":"213.97232ms","start":"2026-05-12T15:18:37.279779Z","end":"2026-05-12T15:18:37.493752Z","steps":["trace[1225166674] 'read index received' (duration: 21.957463ms)","trace[1225166674] 'applied index is now lower than readState.Index' (duration: 192.013727ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:18:37.493976Z","caller":"traceutil/trace.go:171","msg":"trace[2136904338] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"221.923489ms","start":"2026-05-12T15:18:37.272042Z","end":"2026-05-12T15:18:37.493965Z","steps":["trace[2136904338] 'process raft request' (duration: 29.685357ms)","trace[2136904338] 'compare' (duration: 191.693849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:18:37.494124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.982892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-w5lwz\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-05-12T15:18:37.494178Z","caller":"traceutil/trace.go:171","msg":"trace[1694863006] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-w5lwz; range_end:; response_count:1; response_revision:5154; }","duration":"146.072014ms","start":"2026-05-12T15:18:37.348093Z","end":"2026-05-12T15:18:37.494165Z","steps":["trace[1694863006] 'agreement among raft nodes before linearized reading' (duration: 145.88421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:18:37.494307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.535493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T15:18:37.494337Z","caller":"traceutil/trace.go:171","msg":"trace[1572200822] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5154; }","duration":"214.568284ms","start":"2026-05-12T15:18:37.279761Z","end":"2026-05-12T15:18:37.494329Z","steps":["trace[1572200822] 'agreement among raft nodes before linearized reading' (duration: 214.470122ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:18:44.787147Z","caller":"traceutil/trace.go:171","msg":"trace[858575424] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"183.247028ms","start":"2026-05-12T15:18:44.603869Z","end":"2026-05-12T15:18:44.787116Z","steps":["trace[858575424] 'process raft request' (duration: 179.53149ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:18:44.78731Z","caller":"traceutil/trace.go:171","msg":"trace[1905520413] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"121.542201ms","start":"2026-05-12T15:18:44.665718Z","end":"2026-05-12T15:18:44.78726Z","steps":["trace[1905520413] 'process raft request' (duration: 121.223293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:18:46.598063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.535402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T15:18:46.598194Z","caller":"traceutil/trace.go:171","msg":"trace[385602843] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:5316; }","duration":"103.760969ms","start":"2026-05-12T15:18:46.494406Z","end":"2026-05-12T15:18:46.598167Z","steps":["trace[385602843] 'count revisions from in-memory index tree' (duration: 103.412419ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:18:48.924433Z","caller":"traceutil/trace.go:171","msg":"trace[1741173722] transaction","detail":"{read_only:false; response_revision:5372; number_of_response:1; }","duration":"113.597873ms","start":"2026-05-12T15:18:48.810795Z","end":"2026-05-12T15:18:48.924393Z","steps":["trace[1741173722] 'process raft request' (duration: 89.923169ms)","trace[1741173722] 'compare' (duration: 23.416298ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:20:09.877606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2981} {"level":"info","ts":"2026-05-12T15:20:09.956944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2981,"took":"77.91713ms","hash":183720139,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":33996800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-12T15:20:09.957002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":183720139,"revision":2981,"compact-revision":-1} {"level":"warn","ts":"2026-05-12T15:25:02.881655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.557129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T15:25:02.881908Z","caller":"traceutil/trace.go:171","msg":"trace[1623405699] linearizableReadLoop","detail":"{readStateIndex:8532; appliedIndex:8530; }","duration":"158.034222ms","start":"2026-05-12T15:25:02.72385Z","end":"2026-05-12T15:25:02.881884Z","steps":["trace[1623405699] 'read index received' (duration: 54.821µs)","trace[1623405699] 'applied index is now lower than readState.Index' (duration: 157.977951ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:25:02.881938Z","caller":"traceutil/trace.go:171","msg":"trace[244401717] transaction","detail":"{read_only:false; response_revision:8238; number_of_response:1; }","duration":"314.274972ms","start":"2026-05-12T15:25:02.567626Z","end":"2026-05-12T15:25:02.881901Z","steps":["trace[244401717] 'process raft request' (duration: 102.333494ms)","trace[244401717] 'compare' (duration: 211.348903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:25:02.88217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.319629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-12T15:25:02.882231Z","caller":"traceutil/trace.go:171","msg":"trace[1746474471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8239; }","duration":"158.383231ms","start":"2026-05-12T15:25:02.723833Z","end":"2026-05-12T15:25:02.882216Z","steps":["trace[1746474471] 'agreement among raft nodes before linearized reading' (duration: 158.159955ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:25:02.882181Z","caller":"traceutil/trace.go:171","msg":"trace[30656982] transaction","detail":"{read_only:false; response_revision:8239; number_of_response:1; }","duration":"313.403801ms","start":"2026-05-12T15:25:02.568554Z","end":"2026-05-12T15:25:02.881958Z","steps":["trace[30656982] 'process raft request' (duration: 313.236897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:25:02.882168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:25:02.5676Z","time spent":"314.422825ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:25:02.882911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:25:02.56854Z","time spent":"313.792219ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:25:02.946687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.786351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T15:25:02.946768Z","caller":"traceutil/trace.go:171","msg":"trace[1505056299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8240; }","duration":"158.872873ms","start":"2026-05-12T15:25:02.787873Z","end":"2026-05-12T15:25:02.946746Z","steps":["trace[1505056299] 'agreement among raft nodes before linearized reading' (duration: 158.704879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:25:02.946912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.780093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T15:25:02.947051Z","caller":"traceutil/trace.go:171","msg":"trace[378052258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8240; }","duration":"221.943597ms","start":"2026-05-12T15:25:02.725079Z","end":"2026-05-12T15:25:02.947023Z","steps":["trace[378052258] 'agreement among raft nodes before linearized reading' (duration: 221.513157ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:25:04.562951Z","caller":"traceutil/trace.go:171","msg":"trace[1201783887] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"180.55283ms","start":"2026-05-12T15:25:04.382367Z","end":"2026-05-12T15:25:04.56292Z","steps":["trace[1201783887] 'process raft request' (duration: 153.811611ms)","trace[1201783887] 'compare' (duration: 26.596275ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:25:09.884214Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6117} {"level":"info","ts":"2026-05-12T15:25:09.97971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6117,"took":"92.653141ms","hash":3093838557,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":23052288,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-12T15:25:09.979792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3093838557,"revision":6117,"compact-revision":2981} {"level":"info","ts":"2026-05-12T15:25:31.258264Z","caller":"traceutil/trace.go:171","msg":"trace[1834776807] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"283.68023ms","start":"2026-05-12T15:25:30.974561Z","end":"2026-05-12T15:25:31.258242Z","steps":["trace[1834776807] 'process raft request' (duration: 283.497456ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:25:31.377313Z","caller":"traceutil/trace.go:171","msg":"trace[1062284718] linearizableReadLoop","detail":"{readStateIndex:8750; appliedIndex:8748; }","duration":"308.432051ms","start":"2026-05-12T15:25:31.06886Z","end":"2026-05-12T15:25:31.377292Z","steps":["trace[1062284718] 'read index received' (duration: 189.238067ms)","trace[1062284718] 'applied index is now lower than readState.Index' (duration: 119.193044ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:25:31.377635Z","caller":"traceutil/trace.go:171","msg":"trace[296104744] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"403.034109ms","start":"2026-05-12T15:25:30.974591Z","end":"2026-05-12T15:25:31.377625Z","steps":["trace[296104744] 'process raft request' (duration: 388.556423ms)","trace[296104744] 'compare' (duration: 14.053765ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:25:31.377741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:25:30.974563Z","time spent":"403.099591ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:25:31.377951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.075707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T15:25:31.377978Z","caller":"traceutil/trace.go:171","msg":"trace[1870352851] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:8448; }","duration":"309.142689ms","start":"2026-05-12T15:25:31.068829Z","end":"2026-05-12T15:25:31.377972Z","steps":["trace[1870352851] 'agreement among raft nodes before linearized reading' (duration: 309.079498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:25:31.377997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:25:31.068813Z","time spent":"309.17927ms","remote":"127.0.0.1:46334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-12T15:25:31.378287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.393133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T15:25:31.378356Z","caller":"traceutil/trace.go:171","msg":"trace[1199894808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8448; }","duration":"288.491306ms","start":"2026-05-12T15:25:31.089846Z","end":"2026-05-12T15:25:31.378337Z","steps":["trace[1199894808] 'agreement among raft nodes before linearized reading' (duration: 288.306722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:25:31.378538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.604331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T15:25:31.378561Z","caller":"traceutil/trace.go:171","msg":"trace[1973208474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8448; }","duration":"281.663283ms","start":"2026-05-12T15:25:31.096892Z","end":"2026-05-12T15:25:31.378555Z","steps":["trace[1973208474] 'agreement among raft nodes before linearized reading' (duration: 281.601432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:25:31.378538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.544699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T15:25:31.378629Z","caller":"traceutil/trace.go:171","msg":"trace[1463902889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8448; }","duration":"100.662483ms","start":"2026-05-12T15:25:31.27796Z","end":"2026-05-12T15:25:31.378622Z","steps":["trace[1463902889] 'agreement among raft nodes before linearized reading' (duration: 100.508759ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:25:34.615014Z","caller":"traceutil/trace.go:171","msg":"trace[1716577265] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"138.797443ms","start":"2026-05-12T15:25:34.476182Z","end":"2026-05-12T15:25:34.614979Z","steps":["trace[1716577265] 'process raft request' (duration: 138.549017ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:25:43.582375Z","caller":"traceutil/trace.go:171","msg":"trace[1557843465] linearizableReadLoop","detail":"{readStateIndex:8862; appliedIndex:8861; }","duration":"148.583726ms","start":"2026-05-12T15:25:43.43377Z","end":"2026-05-12T15:25:43.582354Z","steps":["trace[1557843465] 'read index received' (duration: 148.496804ms)","trace[1557843465] 'applied index is now lower than readState.Index' (duration: 86.142µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:25:43.582426Z","caller":"traceutil/trace.go:171","msg":"trace[1910159579] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"170.685833ms","start":"2026-05-12T15:25:43.411719Z","end":"2026-05-12T15:25:43.582405Z","steps":["trace[1910159579] 'process raft request' (duration: 170.492149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:25:43.582691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.897593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-12T15:25:43.582814Z","caller":"traceutil/trace.go:171","msg":"trace[1738504762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8555; }","duration":"149.047857ms","start":"2026-05-12T15:25:43.433744Z","end":"2026-05-12T15:25:43.582792Z","steps":["trace[1738504762] 'agreement among raft nodes before linearized reading' (duration: 148.783251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:25:43.84604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.358641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T15:25:43.846211Z","caller":"traceutil/trace.go:171","msg":"trace[1979987353] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"259.77503ms","start":"2026-05-12T15:25:43.586419Z","end":"2026-05-12T15:25:43.846194Z","steps":["trace[1979987353] 'process raft request' (duration: 259.720278ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:25:43.846406Z","caller":"traceutil/trace.go:171","msg":"trace[1967887384] linearizableReadLoop","detail":"{readStateIndex:8863; appliedIndex:8862; }","duration":"263.96702ms","start":"2026-05-12T15:25:43.582428Z","end":"2026-05-12T15:25:43.846395Z","steps":["trace[1967887384] 'read index received' (duration: 76.262691ms)","trace[1967887384] 'applied index is now lower than readState.Index' (duration: 187.703479ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:25:43.846409Z","caller":"traceutil/trace.go:171","msg":"trace[1798658896] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"376.957327ms","start":"2026-05-12T15:25:43.469418Z","end":"2026-05-12T15:25:43.846375Z","steps":["trace[1798658896] 'process raft request' (duration: 189.188846ms)","trace[1798658896] 'compare' (duration: 187.184646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:25:43.846616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.965025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T15:25:43.846654Z","caller":"traceutil/trace.go:171","msg":"trace[1040518751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8557; }","duration":"400.023817ms","start":"2026-05-12T15:25:43.446621Z","end":"2026-05-12T15:25:43.846645Z","steps":["trace[1040518751] 'agreement among raft nodes before linearized reading' (duration: 399.900794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:25:43.846686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:25:43.446611Z","time spent":"400.067328ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T15:25:43.846703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:25:43.469397Z","time spent":"377.183383ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:25:43.846883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.602642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-12T15:25:43.846917Z","caller":"traceutil/trace.go:171","msg":"trace[1863880960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8557; }","duration":"408.671303ms","start":"2026-05-12T15:25:43.438238Z","end":"2026-05-12T15:25:43.846909Z","steps":["trace[1863880960] 'agreement among raft nodes before linearized reading' (duration: 408.586141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:25:43.846943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:25:43.43822Z","time spent":"408.717154ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T15:25:43.871207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.954055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-12T15:25:43.871265Z","caller":"traceutil/trace.go:171","msg":"trace[2073382694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8561; }","duration":"245.059588ms","start":"2026-05-12T15:25:43.626193Z","end":"2026-05-12T15:25:43.871252Z","steps":["trace[2073382694] 'agreement among raft nodes before linearized reading' (duration: 244.892264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:25:43.871912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.643715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T15:25:43.871943Z","caller":"traceutil/trace.go:171","msg":"trace[204565834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8561; }","duration":"137.724307ms","start":"2026-05-12T15:25:43.734211Z","end":"2026-05-12T15:25:43.871935Z","steps":["trace[204565834] 'agreement among raft nodes before linearized reading' (duration: 137.129542ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:26:30.388324Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244c.wal"} {"level":"warn","ts":"2026-05-12T15:27:00.455667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.069707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T15:27:00.455975Z","caller":"traceutil/trace.go:171","msg":"trace[357205735] transaction","detail":"{read_only:false; response_revision:9306; number_of_response:1; }","duration":"201.500999ms","start":"2026-05-12T15:27:00.25442Z","end":"2026-05-12T15:27:00.455921Z","steps":["trace[357205735] 'process raft request' (duration: 68.084504ms)","trace[357205735] 'compare' (duration: 132.83145ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:27:00.456595Z","caller":"traceutil/trace.go:171","msg":"trace[1987160771] transaction","detail":"{read_only:false; response_revision:9307; number_of_response:1; }","duration":"202.111273ms","start":"2026-05-12T15:27:00.25446Z","end":"2026-05-12T15:27:00.456571Z","steps":["trace[1987160771] 'process raft request' (duration: 201.330775ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:27:00.456904Z","caller":"traceutil/trace.go:171","msg":"trace[1107633381] transaction","detail":"{read_only:false; response_revision:9308; number_of_response:1; }","duration":"202.346058ms","start":"2026-05-12T15:27:00.254525Z","end":"2026-05-12T15:27:00.456871Z","steps":["trace[1107633381] 'process raft request' (duration: 201.99911ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:27:50.670911Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T15:27:50.685945Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-12T15:27:50.68706Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-12T15:29:17.976892Z","caller":"traceutil/trace.go:171","msg":"trace[882310464] transaction","detail":"{read_only:false; response_revision:10352; number_of_response:1; }","duration":"102.952984ms","start":"2026-05-12T15:29:17.873912Z","end":"2026-05-12T15:29:17.976865Z","steps":["trace[882310464] 'process raft request' (duration: 102.740489ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:30:09.892086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8286} {"level":"info","ts":"2026-05-12T15:30:09.966383Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8286,"took":"72.326524ms","hash":3262551663,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":26337280,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-12T15:30:09.966471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3262551663,"revision":8286,"compact-revision":6117} {"level":"info","ts":"2026-05-12T15:30:41.369522Z","caller":"traceutil/trace.go:171","msg":"trace[1475432505] transaction","detail":"{read_only:false; response_revision:11135; number_of_response:1; }","duration":"100.512176ms","start":"2026-05-12T15:30:41.268975Z","end":"2026-05-12T15:30:41.369488Z","steps":["trace[1475432505] 'process raft request' (duration: 100.165497ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:30:41.503856Z","caller":"traceutil/trace.go:171","msg":"trace[822408908] transaction","detail":"{read_only:false; response_revision:11138; number_of_response:1; }","duration":"102.707059ms","start":"2026-05-12T15:30:41.401122Z","end":"2026-05-12T15:30:41.503829Z","steps":["trace[822408908] 'process raft request' (duration: 94.947954ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:30:42.539833Z","caller":"traceutil/trace.go:171","msg":"trace[1535100663] linearizableReadLoop","detail":"{readStateIndex:11526; appliedIndex:11525; }","duration":"122.942041ms","start":"2026-05-12T15:30:42.416864Z","end":"2026-05-12T15:30:42.539806Z","steps":["trace[1535100663] 'read index received' (duration: 122.797537ms)","trace[1535100663] 'applied index is now lower than readState.Index' (duration: 143.524µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:30:42.539896Z","caller":"traceutil/trace.go:171","msg":"trace[169498209] transaction","detail":"{read_only:false; response_revision:11146; number_of_response:1; }","duration":"140.834827ms","start":"2026-05-12T15:30:42.399028Z","end":"2026-05-12T15:30:42.539862Z","steps":["trace[169498209] 'process raft request' (duration: 140.627192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:30:42.540068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.591971ms","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-05-12T15:30:42.540046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.156315ms","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-05-12T15:30:42.54013Z","caller":"traceutil/trace.go:171","msg":"trace[1904117733] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11146; }","duration":"122.685974ms","start":"2026-05-12T15:30:42.417428Z","end":"2026-05-12T15:30:42.540114Z","steps":["trace[1904117733] 'agreement among raft nodes before linearized reading' (duration: 122.577541ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:30:42.540153Z","caller":"traceutil/trace.go:171","msg":"trace[1278676908] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11146; }","duration":"123.316139ms","start":"2026-05-12T15:30:42.416813Z","end":"2026-05-12T15:30:42.540129Z","steps":["trace[1278676908] 'agreement among raft nodes before linearized reading' (duration: 123.102094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:30:42.54017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.455868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T15:30:42.540194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.548511ms","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-05-12T15:30:42.540261Z","caller":"traceutil/trace.go:171","msg":"trace[195449414] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11146; }","duration":"122.601833ms","start":"2026-05-12T15:30:42.417635Z","end":"2026-05-12T15:30:42.540237Z","steps":["trace[195449414] 'agreement among raft nodes before linearized reading' (duration: 122.412607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:30:42.54029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.773467ms","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-05-12T15:30:42.540311Z","caller":"traceutil/trace.go:171","msg":"trace[1861702243] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11146; }","duration":"122.699525ms","start":"2026-05-12T15:30:42.417592Z","end":"2026-05-12T15:30:42.540292Z","steps":["trace[1861702243] 'agreement among raft nodes before linearized reading' (duration: 122.467479ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:30:42.540355Z","caller":"traceutil/trace.go:171","msg":"trace[1002257627] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11146; }","duration":"122.879619ms","start":"2026-05-12T15:30:42.417455Z","end":"2026-05-12T15:30:42.540335Z","steps":["trace[1002257627] 'agreement among raft nodes before linearized reading' (duration: 122.641223ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:35:09.898219Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10908} {"level":"info","ts":"2026-05-12T15:35:09.978339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10908,"took":"77.488606ms","hash":2170589181,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":27758592,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-12T15:35:09.978423Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2170589181,"revision":10908,"compact-revision":8286} {"level":"info","ts":"2026-05-12T15:36:38.018688Z","caller":"traceutil/trace.go:171","msg":"trace[1487436731] transaction","detail":"{read_only:false; response_revision:13562; number_of_response:1; }","duration":"136.096472ms","start":"2026-05-12T15:36:37.882565Z","end":"2026-05-12T15:36:38.018662Z","steps":["trace[1487436731] 'process raft request' (duration: 135.956169ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:36:38.021652Z","caller":"traceutil/trace.go:171","msg":"trace[1370058788] transaction","detail":"{read_only:false; response_revision:13563; number_of_response:1; }","duration":"135.636991ms","start":"2026-05-12T15:36:37.885947Z","end":"2026-05-12T15:36:38.021584Z","steps":["trace[1370058788] 'process raft request' (duration: 135.260872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:37:16.523502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.805183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:16 size:151650"} {"level":"info","ts":"2026-05-12T15:37:16.523622Z","caller":"traceutil/trace.go:171","msg":"trace[545212351] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:16; response_revision:13784; }","duration":"114.945136ms","start":"2026-05-12T15:37:16.408659Z","end":"2026-05-12T15:37:16.523604Z","steps":["trace[545212351] 'range keys from in-memory index tree' (duration: 114.497855ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:37:17.533591Z","caller":"traceutil/trace.go:171","msg":"trace[82493308] linearizableReadLoop","detail":"{readStateIndex:14260; appliedIndex:14259; }","duration":"192.411026ms","start":"2026-05-12T15:37:17.341149Z","end":"2026-05-12T15:37:17.53356Z","steps":["trace[82493308] 'read index received' (duration: 192.082648ms)","trace[82493308] 'applied index is now lower than readState.Index' (duration: 327.208µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:37:17.533633Z","caller":"traceutil/trace.go:171","msg":"trace[665707177] transaction","detail":"{read_only:false; response_revision:13787; number_of_response:1; }","duration":"277.092249ms","start":"2026-05-12T15:37:17.256506Z","end":"2026-05-12T15:37:17.533598Z","steps":["trace[665707177] 'process raft request' (duration: 276.831803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:37:17.533843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.678423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T15:37:17.533881Z","caller":"traceutil/trace.go:171","msg":"trace[1451055719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13787; }","duration":"192.781375ms","start":"2026-05-12T15:37:17.34109Z","end":"2026-05-12T15:37:17.533871Z","steps":["trace[1451055719] 'agreement among raft nodes before linearized reading' (duration: 192.631401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:37:17.53402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.544631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-12T15:37:17.534091Z","caller":"traceutil/trace.go:171","msg":"trace[832587852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13787; }","duration":"151.668954ms","start":"2026-05-12T15:37:17.382404Z","end":"2026-05-12T15:37:17.534073Z","steps":["trace[832587852] 'agreement among raft nodes before linearized reading' (duration: 151.459549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:37:17.534183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.116594ms","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-05-12T15:37:17.534201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.355463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-12T15:37:17.534284Z","caller":"traceutil/trace.go:171","msg":"trace[410892608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13787; }","duration":"137.28347ms","start":"2026-05-12T15:37:17.396979Z","end":"2026-05-12T15:37:17.534263Z","steps":["trace[410892608] 'agreement among raft nodes before linearized reading' (duration: 136.945831ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:37:17.534319Z","caller":"traceutil/trace.go:171","msg":"trace[2075974388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13787; }","duration":"148.538699ms","start":"2026-05-12T15:37:17.385756Z","end":"2026-05-12T15:37:17.534295Z","steps":["trace[2075974388] 'agreement among raft nodes before linearized reading' (duration: 148.161189ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:37:17.666041Z","caller":"traceutil/trace.go:171","msg":"trace[689059218] transaction","detail":"{read_only:false; response_revision:13788; number_of_response:1; }","duration":"128.314735ms","start":"2026-05-12T15:37:17.53769Z","end":"2026-05-12T15:37:17.666005Z","steps":["trace[689059218] 'process raft request' (duration: 92.743975ms)","trace[689059218] 'compare' (duration: 35.399175ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:37:17.666342Z","caller":"traceutil/trace.go:171","msg":"trace[2054245103] transaction","detail":"{read_only:false; response_revision:13789; number_of_response:1; }","duration":"128.370707ms","start":"2026-05-12T15:37:17.537959Z","end":"2026-05-12T15:37:17.66633Z","steps":["trace[2054245103] 'process raft request' (duration: 128.004218ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:37:17.666453Z","caller":"traceutil/trace.go:171","msg":"trace[145386396] transaction","detail":"{read_only:false; response_revision:13790; number_of_response:1; }","duration":"128.106611ms","start":"2026-05-12T15:37:17.538338Z","end":"2026-05-12T15:37:17.666444Z","steps":["trace[145386396] 'process raft request' (duration: 127.841904ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:37:17.666639Z","caller":"traceutil/trace.go:171","msg":"trace[147587062] transaction","detail":"{read_only:false; response_revision:13791; number_of_response:1; }","duration":"128.207553ms","start":"2026-05-12T15:37:17.538408Z","end":"2026-05-12T15:37:17.666615Z","steps":["trace[147587062] 'process raft request' (duration: 127.908946ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:38:54.871041Z","caller":"traceutil/trace.go:171","msg":"trace[267124417] transaction","detail":"{read_only:false; response_revision:14853; number_of_response:1; }","duration":"132.21599ms","start":"2026-05-12T15:38:54.738779Z","end":"2026-05-12T15:38:54.870995Z","steps":["trace[267124417] 'process raft request' (duration: 131.940412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:38:55.820566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.394778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-vcvm5\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-05-12T15:38:55.820645Z","caller":"traceutil/trace.go:171","msg":"trace[293769580] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-vcvm5; range_end:; response_count:1; response_revision:14856; }","duration":"105.531572ms","start":"2026-05-12T15:38:55.715096Z","end":"2026-05-12T15:38:55.820627Z","steps":["trace[293769580] 'range keys from in-memory index tree' (duration: 105.225143ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:40:09.904619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12974} {"level":"info","ts":"2026-05-12T15:40:09.978301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12974,"took":"71.000976ms","hash":3531948708,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":30052352,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T15:40:09.978372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3531948708,"revision":12974,"compact-revision":10908} {"level":"info","ts":"2026-05-12T15:44:55.675518Z","caller":"traceutil/trace.go:171","msg":"trace[483962400] linearizableReadLoop","detail":"{readStateIndex:18089; appliedIndex:18088; }","duration":"142.55625ms","start":"2026-05-12T15:44:55.532941Z","end":"2026-05-12T15:44:55.675497Z","steps":["trace[483962400] 'read index received' (duration: 142.453658ms)","trace[483962400] 'applied index is now lower than readState.Index' (duration: 101.702µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:44:55.675565Z","caller":"traceutil/trace.go:171","msg":"trace[2145603528] transaction","detail":"{read_only:false; response_revision:17494; number_of_response:1; }","duration":"167.259272ms","start":"2026-05-12T15:44:55.508286Z","end":"2026-05-12T15:44:55.675545Z","steps":["trace[2145603528] 'process raft request' (duration: 167.073457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:44:55.675793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.803426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zrz25\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-12T15:44:55.675875Z","caller":"traceutil/trace.go:171","msg":"trace[103776921] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zrz25; range_end:; response_count:1; response_revision:17494; }","duration":"142.939739ms","start":"2026-05-12T15:44:55.532889Z","end":"2026-05-12T15:44:55.675829Z","steps":["trace[103776921] 'agreement among raft nodes before linearized reading' (duration: 142.723954ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:44:56.05259Z","caller":"traceutil/trace.go:171","msg":"trace[1453860016] transaction","detail":"{read_only:false; response_revision:17496; number_of_response:1; }","duration":"120.789269ms","start":"2026-05-12T15:44:55.931775Z","end":"2026-05-12T15:44:56.052564Z","steps":["trace[1453860016] 'process raft request' (duration: 120.634636ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:44:56.109885Z","caller":"traceutil/trace.go:171","msg":"trace[1397169106] linearizableReadLoop","detail":"{readStateIndex:18092; appliedIndex:18090; }","duration":"160.786976ms","start":"2026-05-12T15:44:55.949076Z","end":"2026-05-12T15:44:56.109863Z","steps":["trace[1397169106] 'read index received' (duration: 103.412244ms)","trace[1397169106] 'applied index is now lower than readState.Index' (duration: 57.373762ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:44:56.109928Z","caller":"traceutil/trace.go:171","msg":"trace[348881940] transaction","detail":"{read_only:false; response_revision:17497; number_of_response:1; }","duration":"176.950993ms","start":"2026-05-12T15:44:55.932943Z","end":"2026-05-12T15:44:56.109894Z","steps":["trace[348881940] 'process raft request' (duration: 161.915274ms)","trace[348881940] 'compare' (duration: 14.813994ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:44:56.11015Z","caller":"traceutil/trace.go:171","msg":"trace[2100756282] transaction","detail":"{read_only:false; response_revision:17498; number_of_response:1; }","duration":"116.127488ms","start":"2026-05-12T15:44:55.994007Z","end":"2026-05-12T15:44:56.110135Z","steps":["trace[2100756282] 'process raft request' (duration: 115.78683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:44:56.110224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.105276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:44:56.110307Z","caller":"traceutil/trace.go:171","msg":"trace[1240203970] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:17498; }","duration":"126.24637ms","start":"2026-05-12T15:44:55.98404Z","end":"2026-05-12T15:44:56.110287Z","steps":["trace[1240203970] 'agreement among raft nodes before linearized reading' (duration: 126.113586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:44:56.110341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.892389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-12T15:44:56.110382Z","caller":"traceutil/trace.go:171","msg":"trace[1518967384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17498; }","duration":"104.97787ms","start":"2026-05-12T15:44:56.005391Z","end":"2026-05-12T15:44:56.110369Z","steps":["trace[1518967384] 'agreement among raft nodes before linearized reading' (duration: 104.820957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:44:56.110367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.276578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-12T15:44:56.110369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.062286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T15:44:56.110445Z","caller":"traceutil/trace.go:171","msg":"trace[1351782426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17498; }","duration":"161.416371ms","start":"2026-05-12T15:44:55.949009Z","end":"2026-05-12T15:44:56.110426Z","steps":["trace[1351782426] 'agreement among raft nodes before linearized reading' (duration: 161.164575ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:44:56.110459Z","caller":"traceutil/trace.go:171","msg":"trace[2141527558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17498; }","duration":"103.168338ms","start":"2026-05-12T15:44:56.007269Z","end":"2026-05-12T15:44:56.110437Z","steps":["trace[2141527558] 'agreement among raft nodes before linearized reading' (duration: 102.899372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:44:56.110444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.418733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-12T15:44:56.110566Z","caller":"traceutil/trace.go:171","msg":"trace[96262493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17498; }","duration":"108.600278ms","start":"2026-05-12T15:44:56.00194Z","end":"2026-05-12T15:44:56.110541Z","steps":["trace[96262493] 'agreement among raft nodes before linearized reading' (duration: 108.303221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:44:56.465084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.382305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T15:44:56.465197Z","caller":"traceutil/trace.go:171","msg":"trace[47225761] transaction","detail":"{read_only:false; response_revision:17499; number_of_response:1; }","duration":"349.976981ms","start":"2026-05-12T15:44:56.115204Z","end":"2026-05-12T15:44:56.465181Z","steps":["trace[47225761] 'process raft request' (duration: 207.332719ms)","trace[47225761] 'compare' (duration: 142.268423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:44:56.465249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:44:56.115167Z","time spent":"350.061613ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:44:56.558798Z","caller":"traceutil/trace.go:171","msg":"trace[623862608] linearizableReadLoop","detail":"{readStateIndex:18097; appliedIndex:18094; }","duration":"200.7156ms","start":"2026-05-12T15:44:56.358059Z","end":"2026-05-12T15:44:56.558775Z","steps":["trace[623862608] 'read index received' (duration: 200.615058ms)","trace[623862608] 'applied index is now lower than readState.Index' (duration: 99.772µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:44:56.558825Z","caller":"traceutil/trace.go:171","msg":"trace[2073352469] transaction","detail":"{read_only:false; response_revision:17501; number_of_response:1; }","duration":"443.424136ms","start":"2026-05-12T15:44:56.115377Z","end":"2026-05-12T15:44:56.558801Z","steps":["trace[2073352469] 'process raft request' (duration: 443.336094ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:44:56.558825Z","caller":"traceutil/trace.go:171","msg":"trace[78001384] transaction","detail":"{read_only:false; response_revision:17500; number_of_response:1; }","duration":"443.484147ms","start":"2026-05-12T15:44:56.115309Z","end":"2026-05-12T15:44:56.558793Z","steps":["trace[78001384] 'process raft request' (duration: 443.287362ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:44:56.558876Z","caller":"traceutil/trace.go:171","msg":"trace[1897111572] transaction","detail":"{read_only:false; response_revision:17502; number_of_response:1; }","duration":"442.671358ms","start":"2026-05-12T15:44:56.116159Z","end":"2026-05-12T15:44:56.558831Z","steps":["trace[1897111572] 'process raft request' (duration: 442.585896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:44:56.558967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:44:56.115372Z","time spent":"443.509668ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:44:56.55896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:44:56.115288Z","time spent":"443.59962ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:44:56.559015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.931386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T15:44:56.559057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:44:56.116137Z","time spent":"442.817962ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:44:56.559092Z","caller":"traceutil/trace.go:171","msg":"trace[239094720] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:17502; }","duration":"201.030318ms","start":"2026-05-12T15:44:56.358044Z","end":"2026-05-12T15:44:56.559074Z","steps":["trace[239094720] 'agreement among raft nodes before linearized reading' (duration: 200.866204ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:44:57.061418Z","caller":"traceutil/trace.go:171","msg":"trace[1540712479] transaction","detail":"{read_only:false; response_revision:17505; number_of_response:1; }","duration":"121.038665ms","start":"2026-05-12T15:44:56.940343Z","end":"2026-05-12T15:44:57.061381Z","steps":["trace[1540712479] 'process raft request' (duration: 120.798789ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:44:57.070845Z","caller":"traceutil/trace.go:171","msg":"trace[1499591790] transaction","detail":"{read_only:false; response_revision:17506; number_of_response:1; }","duration":"114.55677ms","start":"2026-05-12T15:44:56.956254Z","end":"2026-05-12T15:44:57.070811Z","steps":["trace[1499591790] 'process raft request' (duration: 114.308544ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:45:09.911302Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15332} {"level":"info","ts":"2026-05-12T15:45:10.004168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15332,"took":"90.29266ms","hash":2534319658,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":31973376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T15:45:10.004218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2534319658,"revision":15332,"compact-revision":12974} {"level":"info","ts":"2026-05-12T15:48:43.86251Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T15:48:43.867137Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-12T15:48:43.867304Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-12T15:50:09.91804Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17730} {"level":"info","ts":"2026-05-12T15:50:10.01482Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17730,"took":"93.282931ms","hash":3804016358,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":33832960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-12T15:50:10.014957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3804016358,"revision":17730,"compact-revision":15332} {"level":"info","ts":"2026-05-12T15:50:46.075938Z","caller":"traceutil/trace.go:171","msg":"trace[376332584] transaction","detail":"{read_only:false; response_revision:20210; number_of_response:1; }","duration":"125.487822ms","start":"2026-05-12T15:50:45.950425Z","end":"2026-05-12T15:50:46.075913Z","steps":["trace[376332584] 'process raft request' (duration: 125.325898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:50:46.363749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.008443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-12T15:50:46.363831Z","caller":"traceutil/trace.go:171","msg":"trace[168084973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20210; }","duration":"141.123146ms","start":"2026-05-12T15:50:46.222687Z","end":"2026-05-12T15:50:46.36381Z","steps":["trace[168084973] 'range keys from in-memory index tree' (duration: 140.8914ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:50:46.554026Z","caller":"traceutil/trace.go:171","msg":"trace[868613198] transaction","detail":"{read_only:false; response_revision:20213; number_of_response:1; }","duration":"183.786786ms","start":"2026-05-12T15:50:46.370208Z","end":"2026-05-12T15:50:46.553995Z","steps":["trace[868613198] 'process raft request' (duration: 183.458428ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:50:46.55418Z","caller":"traceutil/trace.go:171","msg":"trace[674017308] transaction","detail":"{read_only:false; response_revision:20214; number_of_response:1; }","duration":"183.902689ms","start":"2026-05-12T15:50:46.370255Z","end":"2026-05-12T15:50:46.554158Z","steps":["trace[674017308] 'process raft request' (duration: 183.457498ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:50:46.554076Z","caller":"traceutil/trace.go:171","msg":"trace[1284301519] transaction","detail":"{read_only:false; response_revision:20212; number_of_response:1; }","duration":"183.851677ms","start":"2026-05-12T15:50:46.370195Z","end":"2026-05-12T15:50:46.554046Z","steps":["trace[1284301519] 'process raft request' (duration: 162.310293ms)","trace[1284301519] 'compare' (duration: 21.064083ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:50:46.713115Z","caller":"traceutil/trace.go:171","msg":"trace[29251375] transaction","detail":"{read_only:false; response_revision:20215; number_of_response:1; }","duration":"154.811103ms","start":"2026-05-12T15:50:46.558279Z","end":"2026-05-12T15:50:46.71309Z","steps":["trace[29251375] 'process raft request' (duration: 106.876806ms)","trace[29251375] 'compare' (duration: 47.688611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:50:46.971412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.632083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T15:50:46.971587Z","caller":"traceutil/trace.go:171","msg":"trace[894994007] transaction","detail":"{read_only:false; response_revision:20216; number_of_response:1; }","duration":"253.477633ms","start":"2026-05-12T15:50:46.718092Z","end":"2026-05-12T15:50:46.97157Z","steps":["trace[894994007] 'process raft request' (duration: 148.232556ms)","trace[894994007] 'compare' (duration: 104.425698ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:54:48.686569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.986558ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-12T15:54:48.688062Z","caller":"traceutil/trace.go:171","msg":"trace[855075545] transaction","detail":"{read_only:false; response_revision:22661; number_of_response:1; }","duration":"379.34365ms","start":"2026-05-12T15:54:48.308542Z","end":"2026-05-12T15:54:48.687886Z","steps":["trace[855075545] 'process raft request' (duration: 378.174362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:54:48.688329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:54:48.30852Z","time spent":"379.649436ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:54:48.689781Z","caller":"traceutil/trace.go:171","msg":"trace[940869703] transaction","detail":"{read_only:false; response_revision:22662; number_of_response:1; }","duration":"172.066279ms","start":"2026-05-12T15:54:48.51768Z","end":"2026-05-12T15:54:48.689746Z","steps":["trace[940869703] 'process raft request' (duration: 170.178884ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:55:09.924303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20012} {"level":"info","ts":"2026-05-12T15:55:10.041588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20012,"took":"114.312076ms","hash":1505649081,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":40460288,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-05-12T15:55:10.041648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1505649081,"revision":20012,"compact-revision":17730} {"level":"warn","ts":"2026-05-12T15:58:29.160343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.02459ms","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:6"} {"level":"warn","ts":"2026-05-12T15:58:29.160405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.677135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-12T15:58:29.160458Z","caller":"traceutil/trace.go:171","msg":"trace[541160387] 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:24878; }","duration":"231.168674ms","start":"2026-05-12T15:58:28.929267Z","end":"2026-05-12T15:58:29.160436Z","steps":["trace[541160387] 'count revisions from in-memory index tree' (duration: 230.930938ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:29.160493Z","caller":"traceutil/trace.go:171","msg":"trace[1755922200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24878; }","duration":"180.804269ms","start":"2026-05-12T15:58:28.979667Z","end":"2026-05-12T15:58:29.160471Z","steps":["trace[1755922200] 'range keys from in-memory index tree' (duration: 180.517971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:29.160518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.267424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-12T15:58:29.160617Z","caller":"traceutil/trace.go:171","msg":"trace[2021249809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24878; }","duration":"185.397088ms","start":"2026-05-12T15:58:28.975201Z","end":"2026-05-12T15:58:29.160598Z","steps":["trace[2021249809] 'range keys from in-memory index tree' (duration: 185.10783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:29.160718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.5583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:29.160803Z","caller":"traceutil/trace.go:171","msg":"trace[1300523839] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:24878; }","duration":"116.655183ms","start":"2026-05-12T15:58:29.044116Z","end":"2026-05-12T15:58:29.160771Z","steps":["trace[1300523839] 'count revisions from in-memory index tree' (duration: 116.445588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:29.160785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.843941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T15:58:29.160896Z","caller":"traceutil/trace.go:171","msg":"trace[473192172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24878; }","duration":"189.015184ms","start":"2026-05-12T15:58:28.971859Z","end":"2026-05-12T15:58:29.160874Z","steps":["trace[473192172] 'range keys from in-memory index tree' (duration: 188.588235ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:29.280883Z","caller":"traceutil/trace.go:171","msg":"trace[635614854] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"116.08434ms","start":"2026-05-12T15:58:29.164752Z","end":"2026-05-12T15:58:29.280836Z","steps":["trace[635614854] 'process raft request' (duration: 115.991617ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:29.281128Z","caller":"traceutil/trace.go:171","msg":"trace[328169676] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"116.354886ms","start":"2026-05-12T15:58:29.164749Z","end":"2026-05-12T15:58:29.281104Z","steps":["trace[328169676] 'process raft request' (duration: 115.781461ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:29.301971Z","caller":"traceutil/trace.go:171","msg":"trace[1447416396] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"137.03683ms","start":"2026-05-12T15:58:29.164905Z","end":"2026-05-12T15:58:29.301942Z","steps":["trace[1447416396] 'process raft request' (duration: 136.853316ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:29.302089Z","caller":"traceutil/trace.go:171","msg":"trace[1196287925] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"136.993679ms","start":"2026-05-12T15:58:29.165064Z","end":"2026-05-12T15:58:29.302058Z","steps":["trace[1196287925] 'process raft request' (duration: 136.834845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:29.573691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.829414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:29.573778Z","caller":"traceutil/trace.go:171","msg":"trace[2057205589] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:24882; }","duration":"213.009268ms","start":"2026-05-12T15:58:29.360749Z","end":"2026-05-12T15:58:29.573758Z","steps":["trace[2057205589] 'count revisions from in-memory index tree' (duration: 212.739172ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:29.641036Z","caller":"traceutil/trace.go:171","msg":"trace[2102325669] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"147.114651ms","start":"2026-05-12T15:58:29.493895Z","end":"2026-05-12T15:58:29.64101Z","steps":["trace[2102325669] 'process raft request' (duration: 146.942067ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:34.165538Z","caller":"traceutil/trace.go:171","msg":"trace[1138897231] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"130.849161ms","start":"2026-05-12T15:58:34.034658Z","end":"2026-05-12T15:58:34.165507Z","steps":["trace[1138897231] 'process raft request' (duration: 130.659727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:34.558416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.876183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:34.558513Z","caller":"traceutil/trace.go:171","msg":"trace[1263290697] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:24925; }","duration":"230.031677ms","start":"2026-05-12T15:58:34.328459Z","end":"2026-05-12T15:58:34.558491Z","steps":["trace[1263290697] 'count revisions from in-memory index tree' (duration: 229.765591ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:34.558539Z","caller":"traceutil/trace.go:171","msg":"trace[1744932719] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"180.325397ms","start":"2026-05-12T15:58:34.378163Z","end":"2026-05-12T15:58:34.558489Z","steps":["trace[1744932719] 'process raft request' (duration: 135.288378ms)","trace[1744932719] 'compare' (duration: 44.595827ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:34.845458Z","caller":"traceutil/trace.go:171","msg":"trace[843364956] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"327.068789ms","start":"2026-05-12T15:58:34.518362Z","end":"2026-05-12T15:58:34.845431Z","steps":["trace[843364956] 'process raft request' (duration: 326.912196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:34.845609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:34.518332Z","time spent":"327.202652ms","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:58:35.008882Z","caller":"traceutil/trace.go:171","msg":"trace[668260359] linearizableReadLoop","detail":"{readStateIndex:25746; appliedIndex:25744; }","duration":"234.81918ms","start":"2026-05-12T15:58:34.774042Z","end":"2026-05-12T15:58:35.008861Z","steps":["trace[668260359] 'read index received' (duration: 71.206744ms)","trace[668260359] 'applied index is now lower than readState.Index' (duration: 163.611196ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:35.008923Z","caller":"traceutil/trace.go:171","msg":"trace[562492174] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"291.568938ms","start":"2026-05-12T15:58:34.717326Z","end":"2026-05-12T15:58:35.008895Z","steps":["trace[562492174] 'process raft request' (duration: 291.342143ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:35.008999Z","caller":"traceutil/trace.go:171","msg":"trace[1106367603] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"227.72798ms","start":"2026-05-12T15:58:34.781234Z","end":"2026-05-12T15:58:35.008962Z","steps":["trace[1106367603] 'process raft request' (duration: 227.583787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:35.009429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.268541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-12T15:58:35.009557Z","caller":"traceutil/trace.go:171","msg":"trace[473349885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24929; }","duration":"235.567369ms","start":"2026-05-12T15:58:34.773956Z","end":"2026-05-12T15:58:35.009524Z","steps":["trace[473349885] 'agreement among raft nodes before linearized reading' (duration: 235.033376ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:35.142297Z","caller":"traceutil/trace.go:171","msg":"trace[2019273766] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"178.82936ms","start":"2026-05-12T15:58:34.963439Z","end":"2026-05-12T15:58:35.142269Z","steps":["trace[2019273766] 'process raft request' (duration: 177.501738ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:35.142351Z","caller":"traceutil/trace.go:171","msg":"trace[1127716567] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"128.748691ms","start":"2026-05-12T15:58:35.013571Z","end":"2026-05-12T15:58:35.14232Z","steps":["trace[1127716567] 'process raft request' (duration: 128.653579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:35.142556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.725481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T15:58:35.142631Z","caller":"traceutil/trace.go:171","msg":"trace[1884707620] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:24931; }","duration":"344.864614ms","start":"2026-05-12T15:58:34.797747Z","end":"2026-05-12T15:58:35.142612Z","steps":["trace[1884707620] 'agreement among raft nodes before linearized reading' (duration: 344.6912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:35.142679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:34.797728Z","time spent":"344.940077ms","remote":"127.0.0.1:46370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-12T15:58:35.14284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.795839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-05-12T15:58:35.142388Z","caller":"traceutil/trace.go:171","msg":"trace[1560839981] linearizableReadLoop","detail":"{readStateIndex:25748; appliedIndex:25747; }","duration":"133.312531ms","start":"2026-05-12T15:58:35.009011Z","end":"2026-05-12T15:58:35.142324Z","steps":["trace[1560839981] 'read index received' (duration: 131.943138ms)","trace[1560839981] 'applied index is now lower than readState.Index' (duration: 1.366623ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:35.142989Z","caller":"traceutil/trace.go:171","msg":"trace[443933773] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24931; }","duration":"287.961233ms","start":"2026-05-12T15:58:34.854973Z","end":"2026-05-12T15:58:35.142935Z","steps":["trace[443933773] 'agreement among raft nodes before linearized reading' (duration: 287.383049ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:35.506138Z","caller":"traceutil/trace.go:171","msg":"trace[1242391762] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"149.925489ms","start":"2026-05-12T15:58:35.356187Z","end":"2026-05-12T15:58:35.506112Z","steps":["trace[1242391762] 'process raft request' (duration: 149.766925ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:35.650289Z","caller":"traceutil/trace.go:171","msg":"trace[1933373772] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"291.270602ms","start":"2026-05-12T15:58:35.358991Z","end":"2026-05-12T15:58:35.650261Z","steps":["trace[1933373772] 'process raft request' (duration: 291.172969ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:35.650555Z","caller":"traceutil/trace.go:171","msg":"trace[462445329] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"293.214177ms","start":"2026-05-12T15:58:35.357301Z","end":"2026-05-12T15:58:35.650515Z","steps":["trace[462445329] 'process raft request' (duration: 292.642574ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:35.650678Z","caller":"traceutil/trace.go:171","msg":"trace[1850102242] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"291.693431ms","start":"2026-05-12T15:58:35.358939Z","end":"2026-05-12T15:58:35.650633Z","steps":["trace[1850102242] 'process raft request' (duration: 291.155149ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:35.893534Z","caller":"traceutil/trace.go:171","msg":"trace[266777448] linearizableReadLoop","detail":"{readStateIndex:25756; appliedIndex:25754; }","duration":"161.741861ms","start":"2026-05-12T15:58:35.731774Z","end":"2026-05-12T15:58:35.893516Z","steps":["trace[266777448] 'read index received' (duration: 72.259459ms)","trace[266777448] 'applied index is now lower than readState.Index' (duration: 89.481802ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:35.893572Z","caller":"traceutil/trace.go:171","msg":"trace[316242235] transaction","detail":"{read_only:false; response_revision:24938; number_of_response:1; }","duration":"231.194323ms","start":"2026-05-12T15:58:35.662354Z","end":"2026-05-12T15:58:35.893549Z","steps":["trace[316242235] 'process raft request' (duration: 231.09114ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:35.893585Z","caller":"traceutil/trace.go:171","msg":"trace[1397204519] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"231.227854ms","start":"2026-05-12T15:58:35.662317Z","end":"2026-05-12T15:58:35.893545Z","steps":["trace[1397204519] 'process raft request' (duration: 141.763802ms)","trace[1397204519] 'compare' (duration: 88.998371ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:35.89365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.868935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:35.893678Z","caller":"traceutil/trace.go:171","msg":"trace[1839087892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24938; }","duration":"161.948547ms","start":"2026-05-12T15:58:35.731722Z","end":"2026-05-12T15:58:35.89367Z","steps":["trace[1839087892] 'agreement among raft nodes before linearized reading' (duration: 161.880605ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:36.204029Z","caller":"traceutil/trace.go:171","msg":"trace[1428620008] linearizableReadLoop","detail":"{readStateIndex:25757; appliedIndex:25756; }","duration":"309.55745ms","start":"2026-05-12T15:58:35.894448Z","end":"2026-05-12T15:58:36.204005Z","steps":["trace[1428620008] 'read index received' (duration: 225.361455ms)","trace[1428620008] 'applied index is now lower than readState.Index' (duration: 84.194445ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:36.204068Z","caller":"traceutil/trace.go:171","msg":"trace[566675723] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"379.579486ms","start":"2026-05-12T15:58:35.824455Z","end":"2026-05-12T15:58:36.204034Z","steps":["trace[566675723] 'process raft request' (duration: 295.428361ms)","trace[566675723] 'compare' (duration: 83.800967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:36.204175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.707864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:36.204208Z","caller":"traceutil/trace.go:171","msg":"trace[1278371572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24939; }","duration":"309.777666ms","start":"2026-05-12T15:58:35.894419Z","end":"2026-05-12T15:58:36.204197Z","steps":["trace[1278371572] 'agreement among raft nodes before linearized reading' (duration: 309.698414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:36.204208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:35.824431Z","time spent":"379.701859ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:58:36.204247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:35.894404Z","time spent":"309.835867ms","remote":"127.0.0.1:41964","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-05-12T15:58:36.677769Z","caller":"traceutil/trace.go:171","msg":"trace[434621322] transaction","detail":"{read_only:false; response_revision:24941; number_of_response:1; }","duration":"114.387788ms","start":"2026-05-12T15:58:36.56335Z","end":"2026-05-12T15:58:36.677738Z","steps":["trace[434621322] 'process raft request' (duration: 114.199373ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:37.148252Z","caller":"traceutil/trace.go:171","msg":"trace[1652964501] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"134.331775ms","start":"2026-05-12T15:58:37.013894Z","end":"2026-05-12T15:58:37.148226Z","steps":["trace[1652964501] 'process raft request' (duration: 134.174991ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:37.151761Z","caller":"traceutil/trace.go:171","msg":"trace[1508150538] transaction","detail":"{read_only:false; response_revision:24943; number_of_response:1; }","duration":"137.792498ms","start":"2026-05-12T15:58:37.013939Z","end":"2026-05-12T15:58:37.151732Z","steps":["trace[1508150538] 'process raft request' (duration: 137.595843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:37.430621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.032907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T15:58:37.430844Z","caller":"traceutil/trace.go:171","msg":"trace[1698554591] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"203.47002ms","start":"2026-05-12T15:58:37.227356Z","end":"2026-05-12T15:58:37.430826Z","steps":["trace[1698554591] 'process raft request' (duration: 203.366888ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:37.430919Z","caller":"traceutil/trace.go:171","msg":"trace[1841653764] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"273.492626ms","start":"2026-05-12T15:58:37.157388Z","end":"2026-05-12T15:58:37.43088Z","steps":["trace[1841653764] 'process raft request' (duration: 63.113331ms)","trace[1841653764] 'compare' (duration: 209.7403ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:37.646105Z","caller":"traceutil/trace.go:171","msg":"trace[111188560] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"131.322363ms","start":"2026-05-12T15:58:37.514749Z","end":"2026-05-12T15:58:37.646071Z","steps":["trace[111188560] 'process raft request' (duration: 92.906033ms)","trace[111188560] 'compare' (duration: 38.021281ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:37.881416Z","caller":"traceutil/trace.go:171","msg":"trace[762832037] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"221.83747ms","start":"2026-05-12T15:58:37.659557Z","end":"2026-05-12T15:58:37.881395Z","steps":["trace[762832037] 'process raft request' (duration: 212.181108ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:38.164349Z","caller":"traceutil/trace.go:171","msg":"trace[1015663511] linearizableReadLoop","detail":"{readStateIndex:25768; appliedIndex:25764; }","duration":"431.59385ms","start":"2026-05-12T15:58:37.732727Z","end":"2026-05-12T15:58:38.164321Z","steps":["trace[1015663511] 'read index received' (duration: 139.028846ms)","trace[1015663511] 'applied index is now lower than readState.Index' (duration: 292.561053ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:38.164379Z","caller":"traceutil/trace.go:171","msg":"trace[41501129] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"503.399029ms","start":"2026-05-12T15:58:37.660949Z","end":"2026-05-12T15:58:38.164348Z","steps":["trace[41501129] 'process raft request' (duration: 503.234155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:38.164494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.747084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:38.164538Z","caller":"traceutil/trace.go:171","msg":"trace[220310844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24950; }","duration":"431.844566ms","start":"2026-05-12T15:58:37.732679Z","end":"2026-05-12T15:58:38.164524Z","steps":["trace[220310844] 'agreement among raft nodes before linearized reading' (duration: 431.755994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:38.164546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:37.660916Z","time spent":"503.550923ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:58:38.164528Z","caller":"traceutil/trace.go:171","msg":"trace[1711361004] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"504.789972ms","start":"2026-05-12T15:58:37.659708Z","end":"2026-05-12T15:58:38.164498Z","steps":["trace[1711361004] 'process raft request' (duration: 487.168619ms)","trace[1711361004] 'compare' (duration: 16.71768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:38.164578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:37.732655Z","time spent":"431.913557ms","remote":"127.0.0.1:41964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T15:58:38.164687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:37.659701Z","time spent":"504.916615ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:58:38.164821Z","caller":"traceutil/trace.go:171","msg":"trace[820553665] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"266.478948ms","start":"2026-05-12T15:58:37.898301Z","end":"2026-05-12T15:58:38.16478Z","steps":["trace[820553665] 'process raft request' (duration: 266.233963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:38.164874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.534005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:38.164919Z","caller":"traceutil/trace.go:171","msg":"trace[1317169297] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:24952; }","duration":"232.608977ms","start":"2026-05-12T15:58:37.932298Z","end":"2026-05-12T15:58:38.164907Z","steps":["trace[1317169297] 'agreement among raft nodes before linearized reading' (duration: 232.535876ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:38.164878Z","caller":"traceutil/trace.go:171","msg":"trace[1531533406] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"265.70849ms","start":"2026-05-12T15:58:37.89912Z","end":"2026-05-12T15:58:38.164829Z","steps":["trace[1531533406] 'process raft request' (duration: 265.551666ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:38.164852Z","caller":"traceutil/trace.go:171","msg":"trace[697669476] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"478.176636ms","start":"2026-05-12T15:58:37.686659Z","end":"2026-05-12T15:58:38.164835Z","steps":["trace[697669476] 'process raft request' (duration: 477.599692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:38.165193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:37.686639Z","time spent":"478.514183ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:58:38.164824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.377836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:38.165335Z","caller":"traceutil/trace.go:171","msg":"trace[680123163] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:24952; }","duration":"401.92361ms","start":"2026-05-12T15:58:37.763371Z","end":"2026-05-12T15:58:38.165295Z","steps":["trace[680123163] 'agreement among raft nodes before linearized reading' (duration: 401.350606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:38.165409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:37.763353Z","time spent":"402.032792ms","remote":"127.0.0.1:43268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"info","ts":"2026-05-12T15:58:38.326157Z","caller":"traceutil/trace.go:171","msg":"trace[824549771] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"116.104249ms","start":"2026-05-12T15:58:38.210022Z","end":"2026-05-12T15:58:38.326126Z","steps":["trace[824549771] 'process raft request' (duration: 114.03075ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:38.326359Z","caller":"traceutil/trace.go:171","msg":"trace[1306411063] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"103.405535ms","start":"2026-05-12T15:58:38.222789Z","end":"2026-05-12T15:58:38.326195Z","steps":["trace[1306411063] 'process raft request' (duration: 103.266211ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:51.873667Z","caller":"traceutil/trace.go:171","msg":"trace[1671933052] linearizableReadLoop","detail":"{readStateIndex:25925; appliedIndex:25924; }","duration":"126.939608ms","start":"2026-05-12T15:58:51.746698Z","end":"2026-05-12T15:58:51.873637Z","steps":["trace[1671933052] 'read index received' (duration: 126.718263ms)","trace[1671933052] 'applied index is now lower than readState.Index' (duration: 220.465µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:51.873836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.114463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:51.873869Z","caller":"traceutil/trace.go:171","msg":"trace[1133008882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25103; }","duration":"127.223296ms","start":"2026-05-12T15:58:51.746635Z","end":"2026-05-12T15:58:51.873858Z","steps":["trace[1133008882] 'agreement among raft nodes before linearized reading' (duration: 127.113673ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:51.874205Z","caller":"traceutil/trace.go:171","msg":"trace[588689502] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"155.560314ms","start":"2026-05-12T15:58:51.718567Z","end":"2026-05-12T15:58:51.874127Z","steps":["trace[588689502] 'process raft request' (duration: 154.925198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:52.180151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.581023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T15:58:52.180406Z","caller":"traceutil/trace.go:171","msg":"trace[1916355613] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"232.536496ms","start":"2026-05-12T15:58:51.947853Z","end":"2026-05-12T15:58:52.18039Z","steps":["trace[1916355613] 'process raft request' (duration: 94.652936ms)","trace[1916355613] 'compare' (duration: 137.152563ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:52.180483Z","caller":"traceutil/trace.go:171","msg":"trace[1380673727] linearizableReadLoop","detail":"{readStateIndex:25926; appliedIndex:25925; }","duration":"156.173558ms","start":"2026-05-12T15:58:52.024304Z","end":"2026-05-12T15:58:52.180478Z","steps":["trace[1380673727] 'read index received' (duration: 4.244072ms)","trace[1380673727] 'applied index is now lower than readState.Index' (duration: 151.928606ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:52.180655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.351702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f6ed4a7-b172-4c49-bb5a-3c897f993883\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-12T15:58:52.180677Z","caller":"traceutil/trace.go:171","msg":"trace[1415095873] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f6ed4a7-b172-4c49-bb5a-3c897f993883; range_end:; response_count:1; response_revision:25105; }","duration":"156.418294ms","start":"2026-05-12T15:58:52.024252Z","end":"2026-05-12T15:58:52.18067Z","steps":["trace[1415095873] 'agreement among raft nodes before linearized reading' (duration: 156.316351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:52.180865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.847083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-12T15:58:52.180889Z","caller":"traceutil/trace.go:171","msg":"trace[1553942566] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25105; }","duration":"105.921076ms","start":"2026-05-12T15:58:52.074961Z","end":"2026-05-12T15:58:52.180882Z","steps":["trace[1553942566] 'agreement among raft nodes before linearized reading' (duration: 105.805593ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:52.470174Z","caller":"traceutil/trace.go:171","msg":"trace[1845337028] linearizableReadLoop","detail":"{readStateIndex:25928; appliedIndex:25927; }","duration":"261.698853ms","start":"2026-05-12T15:58:52.208443Z","end":"2026-05-12T15:58:52.470142Z","steps":["trace[1845337028] 'read index received' (duration: 233.645992ms)","trace[1845337028] 'applied index is now lower than readState.Index' (duration: 28.051731ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:52.470339Z","caller":"traceutil/trace.go:171","msg":"trace[768184375] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"282.792818ms","start":"2026-05-12T15:58:52.187505Z","end":"2026-05-12T15:58:52.470298Z","steps":["trace[768184375] 'process raft request' (duration: 254.488721ms)","trace[768184375] 'compare' (duration: 27.990769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:52.470477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.011922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-05-12T15:58:52.470518Z","caller":"traceutil/trace.go:171","msg":"trace[565220594] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25106; }","duration":"262.125815ms","start":"2026-05-12T15:58:52.208378Z","end":"2026-05-12T15:58:52.470504Z","steps":["trace[565220594] 'agreement among raft nodes before linearized reading' (duration: 261.931419ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:52.768177Z","caller":"traceutil/trace.go:171","msg":"trace[441118252] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"547.459954ms","start":"2026-05-12T15:58:52.220683Z","end":"2026-05-12T15:58:52.768143Z","steps":["trace[441118252] 'process raft request' (duration: 490.286155ms)","trace[441118252] 'compare' (duration: 56.106783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:52.768393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.220651Z","time spent":"547.617358ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:58:52.768881Z","caller":"traceutil/trace.go:171","msg":"trace[137846276] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"541.951571ms","start":"2026-05-12T15:58:52.226896Z","end":"2026-05-12T15:58:52.768848Z","steps":["trace[137846276] 'process raft request' (duration: 540.953008ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:52.768925Z","caller":"traceutil/trace.go:171","msg":"trace[1821602587] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"542.750541ms","start":"2026-05-12T15:58:52.226137Z","end":"2026-05-12T15:58:52.768887Z","steps":["trace[1821602587] 'process raft request' (duration: 541.541502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:52.769092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.226125Z","time spent":"542.870444ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:58:52.769169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.226889Z","time spent":"542.096044ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:58:52.768627Z","caller":"traceutil/trace.go:171","msg":"trace[250894585] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"341.234727ms","start":"2026-05-12T15:58:52.427357Z","end":"2026-05-12T15:58:52.768591Z","steps":["trace[250894585] 'process raft request' (duration: 341.166545ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:52.769536Z","caller":"traceutil/trace.go:171","msg":"trace[92330608] linearizableReadLoop","detail":"{readStateIndex:25934; appliedIndex:25928; }","duration":"299.246123ms","start":"2026-05-12T15:58:52.47027Z","end":"2026-05-12T15:58:52.769516Z","steps":["trace[92330608] 'read index received' (duration: 240.62829ms)","trace[92330608] 'applied index is now lower than readState.Index' (duration: 58.616533ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:52.769693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.427313Z","time spent":"342.257242ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:58:52.769773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.367444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T15:58:52.769818Z","caller":"traceutil/trace.go:171","msg":"trace[221642014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25112; }","duration":"497.415496ms","start":"2026-05-12T15:58:52.272389Z","end":"2026-05-12T15:58:52.769804Z","steps":["trace[221642014] 'agreement among raft nodes before linearized reading' (duration: 497.218391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:52.769854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.272329Z","time spent":"497.518188ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T15:58:52.769869Z","caller":"traceutil/trace.go:171","msg":"trace[1460674624] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"325.189844ms","start":"2026-05-12T15:58:52.444657Z","end":"2026-05-12T15:58:52.769847Z","steps":["trace[1460674624] 'process raft request' (duration: 324.62902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:52.769972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.444625Z","time spent":"325.299466ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:58:52.769976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.11706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T15:58:52.77007Z","caller":"traceutil/trace.go:171","msg":"trace[1007361053] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25112; }","duration":"538.205322ms","start":"2026-05-12T15:58:52.23185Z","end":"2026-05-12T15:58:52.770055Z","steps":["trace[1007361053] 'agreement among raft nodes before linearized reading' (duration: 538.078909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:52.770109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.23184Z","time spent":"538.258845ms","remote":"127.0.0.1:42268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":22,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-05-12T15:58:52.770574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.483778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:52.771323Z","caller":"traceutil/trace.go:171","msg":"trace[1110139202] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25112; }","duration":"372.093237ms","start":"2026-05-12T15:58:52.399198Z","end":"2026-05-12T15:58:52.771291Z","steps":["trace[1110139202] 'agreement among raft nodes before linearized reading' (duration: 370.467797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:52.771449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.399168Z","time spent":"372.26061ms","remote":"127.0.0.1:37924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"info","ts":"2026-05-12T15:58:52.770178Z","caller":"traceutil/trace.go:171","msg":"trace[796147568] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"343.02022ms","start":"2026-05-12T15:58:52.425983Z","end":"2026-05-12T15:58:52.769003Z","steps":["trace[796147568] 'process raft request' (duration: 342.446676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:52.771678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.425947Z","time spent":"345.681964ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:58:52.773731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.599449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-12T15:58:52.773877Z","caller":"traceutil/trace.go:171","msg":"trace[1000600466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25112; }","duration":"540.775974ms","start":"2026-05-12T15:58:52.233091Z","end":"2026-05-12T15:58:52.773867Z","steps":["trace[1000600466] 'agreement among raft nodes before linearized reading' (duration: 536.669505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:52.773906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.233082Z","time spent":"540.816424ms","remote":"127.0.0.1:42212","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-05-12T15:58:53.128942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.216642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T15:58:53.12913Z","caller":"traceutil/trace.go:171","msg":"trace[82378396] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"377.65882ms","start":"2026-05-12T15:58:52.751427Z","end":"2026-05-12T15:58:53.129086Z","steps":["trace[82378396] 'process raft request' (duration: 205.186561ms)","trace[82378396] 'compare' (duration: 171.494705ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:53.12917Z","caller":"traceutil/trace.go:171","msg":"trace[458265376] linearizableReadLoop","detail":"{readStateIndex:25935; appliedIndex:25934; }","duration":"359.561367ms","start":"2026-05-12T15:58:52.769579Z","end":"2026-05-12T15:58:53.12914Z","steps":["trace[458265376] 'read index received' (duration: 187.072288ms)","trace[458265376] 'applied index is now lower than readState.Index' (duration: 172.486179ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:53.129335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.751391Z","time spent":"377.836603ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:58:53.129377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.343062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:53.129416Z","caller":"traceutil/trace.go:171","msg":"trace[1061658452] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25113; }","duration":"527.445345ms","start":"2026-05-12T15:58:52.601957Z","end":"2026-05-12T15:58:53.129402Z","steps":["trace[1061658452] 'agreement among raft nodes before linearized reading' (duration: 527.331612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:53.129467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.601936Z","time spent":"527.522056ms","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T15:58:53.129916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.507316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18aedcab0ee2c59f\" ","response":"range_response_count:1 size:742"} {"level":"warn","ts":"2026-05-12T15:58:53.12992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.126152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1448"} {"level":"info","ts":"2026-05-12T15:58:53.12997Z","caller":"traceutil/trace.go:171","msg":"trace[851058646] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18aedcab0ee2c59f; range_end:; response_count:1; response_revision:25113; }","duration":"654.576897ms","start":"2026-05-12T15:58:52.475378Z","end":"2026-05-12T15:58:53.129955Z","steps":["trace[851058646] 'agreement among raft nodes before linearized reading' (duration: 654.388423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:53.130015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.475363Z","time spent":"654.644899ms","remote":"127.0.0.1:42024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":764,"request content":"key:\"/registry/events/openstack/rabbitmq-manila.18aedcab0ee2c59f\" "} {"level":"info","ts":"2026-05-12T15:58:53.129991Z","caller":"traceutil/trace.go:171","msg":"trace[61596648] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25113; }","duration":"653.193805ms","start":"2026-05-12T15:58:52.476774Z","end":"2026-05-12T15:58:53.129968Z","steps":["trace[61596648] 'agreement among raft nodes before linearized reading' (duration: 652.932988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:53.130152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.476712Z","time spent":"653.382078ms","remote":"127.0.0.1:42140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":1470,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-12T15:58:53.130675Z","caller":"traceutil/trace.go:171","msg":"trace[221408244] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"352.976719ms","start":"2026-05-12T15:58:52.777672Z","end":"2026-05-12T15:58:53.130648Z","steps":["trace[221408244] 'process raft request' (duration: 352.902627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:53.130731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.722641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T15:58:53.130787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.77765Z","time spent":"353.080761ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:58:53.131033Z","caller":"traceutil/trace.go:171","msg":"trace[426442145] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"354.353961ms","start":"2026-05-12T15:58:52.77666Z","end":"2026-05-12T15:58:53.131014Z","steps":["trace[426442145] 'process raft request' (duration: 352.413945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:53.131131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.776631Z","time spent":"354.452333ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:58:53.131218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.882628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:53.131273Z","caller":"traceutil/trace.go:171","msg":"trace[630023699] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25115; }","duration":"322.96532ms","start":"2026-05-12T15:58:52.808296Z","end":"2026-05-12T15:58:53.131261Z","steps":["trace[630023699] 'agreement among raft nodes before linearized reading' (duration: 322.885878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:53.131312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.808283Z","time spent":"323.019991ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"info","ts":"2026-05-12T15:58:53.131634Z","caller":"traceutil/trace.go:171","msg":"trace[1781916335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25115; }","duration":"344.804162ms","start":"2026-05-12T15:58:52.785964Z","end":"2026-05-12T15:58:53.130768Z","steps":["trace[1781916335] 'agreement among raft nodes before linearized reading' (duration: 344.670479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:53.131707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:52.785943Z","time spent":"345.751056ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T15:58:53.39424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.683374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T15:58:53.394359Z","caller":"traceutil/trace.go:171","msg":"trace[1769316060] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"262.623705ms","start":"2026-05-12T15:58:53.131711Z","end":"2026-05-12T15:58:53.394335Z","steps":["trace[1769316060] 'process raft request' (duration: 137.758237ms)","trace[1769316060] 'compare' (duration: 124.460139ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:53.477409Z","caller":"traceutil/trace.go:171","msg":"trace[1478252682] linearizableReadLoop","detail":"{readStateIndex:25939; appliedIndex:25937; }","duration":"341.603096ms","start":"2026-05-12T15:58:53.135779Z","end":"2026-05-12T15:58:53.477382Z","steps":["trace[1478252682] 'read index received' (duration: 133.69888ms)","trace[1478252682] 'applied index is now lower than readState.Index' (duration: 207.903036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:53.477536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:53.13287Z","time spent":"344.66312ms","remote":"127.0.0.1:41978","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-12T15:58:53.477954Z","caller":"traceutil/trace.go:171","msg":"trace[1834539143] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"251.458749ms","start":"2026-05-12T15:58:53.226482Z","end":"2026-05-12T15:58:53.47794Z","steps":["trace[1834539143] 'process raft request' (duration: 250.737162ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:53.478139Z","caller":"traceutil/trace.go:171","msg":"trace[144592152] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"251.559552ms","start":"2026-05-12T15:58:53.22657Z","end":"2026-05-12T15:58:53.478129Z","steps":["trace[144592152] 'process raft request' (duration: 250.767463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:53.478375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.577169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-05-12T15:58:53.478414Z","caller":"traceutil/trace.go:171","msg":"trace[953797216] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25118; }","duration":"342.651811ms","start":"2026-05-12T15:58:53.135752Z","end":"2026-05-12T15:58:53.478404Z","steps":["trace[953797216] 'agreement among raft nodes before linearized reading' (duration: 342.509338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:53.478447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:53.13573Z","time spent":"342.710332ms","remote":"127.0.0.1:42388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9626,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"warn","ts":"2026-05-12T15:58:53.478702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.833328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f6ed4a7-b172-4c49-bb5a-3c897f993883\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-12T15:58:53.478787Z","caller":"traceutil/trace.go:171","msg":"trace[1069455156] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f6ed4a7-b172-4c49-bb5a-3c897f993883; range_end:; response_count:1; response_revision:25118; }","duration":"292.978692ms","start":"2026-05-12T15:58:53.185789Z","end":"2026-05-12T15:58:53.478767Z","steps":["trace[1069455156] 'agreement among raft nodes before linearized reading' (duration: 292.721476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:53.479288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.627001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-12T15:58:53.479311Z","caller":"traceutil/trace.go:171","msg":"trace[891703108] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25118; }","duration":"192.686962ms","start":"2026-05-12T15:58:53.286618Z","end":"2026-05-12T15:58:53.479305Z","steps":["trace[891703108] 'agreement among raft nodes before linearized reading' (duration: 192.619551ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:53.848024Z","caller":"traceutil/trace.go:171","msg":"trace[1564211719] linearizableReadLoop","detail":"{readStateIndex:25946; appliedIndex:25945; }","duration":"115.76074ms","start":"2026-05-12T15:58:53.732237Z","end":"2026-05-12T15:58:53.847998Z","steps":["trace[1564211719] 'read index received' (duration: 61.239496ms)","trace[1564211719] 'applied index is now lower than readState.Index' (duration: 54.519974ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:53.848158Z","caller":"traceutil/trace.go:171","msg":"trace[137874161] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"288.650929ms","start":"2026-05-12T15:58:53.559494Z","end":"2026-05-12T15:58:53.848145Z","steps":["trace[137874161] 'process raft request' (duration: 234.049113ms)","trace[137874161] 'compare' (duration: 53.976341ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:53.848722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.467008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:53.848804Z","caller":"traceutil/trace.go:171","msg":"trace[1815554040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25123; }","duration":"116.596111ms","start":"2026-05-12T15:58:53.73219Z","end":"2026-05-12T15:58:53.848786Z","steps":["trace[1815554040] 'agreement among raft nodes before linearized reading' (duration: 116.483288ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:54.124147Z","caller":"traceutil/trace.go:171","msg":"trace[2088412757] linearizableReadLoop","detail":"{readStateIndex:25947; appliedIndex:25946; }","duration":"190.854308ms","start":"2026-05-12T15:58:53.93327Z","end":"2026-05-12T15:58:54.124125Z","steps":["trace[2088412757] 'read index received' (duration: 137.220485ms)","trace[2088412757] 'applied index is now lower than readState.Index' (duration: 53.632543ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:54.124255Z","caller":"traceutil/trace.go:171","msg":"trace[1340285595] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"241.652364ms","start":"2026-05-12T15:58:53.882586Z","end":"2026-05-12T15:58:54.124239Z","steps":["trace[1340285595] 'process raft request' (duration: 188.01987ms)","trace[1340285595] 'compare' (duration: 53.324087ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:54.124343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.042993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:54.124445Z","caller":"traceutil/trace.go:171","msg":"trace[714476786] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25124; }","duration":"191.181745ms","start":"2026-05-12T15:58:53.933243Z","end":"2026-05-12T15:58:54.124425Z","steps":["trace[714476786] 'agreement among raft nodes before linearized reading' (duration: 191.003401ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:54.340022Z","caller":"traceutil/trace.go:171","msg":"trace[825913070] linearizableReadLoop","detail":"{readStateIndex:25948; appliedIndex:25947; }","duration":"215.786635ms","start":"2026-05-12T15:58:54.124211Z","end":"2026-05-12T15:58:54.339997Z","steps":["trace[825913070] 'read index received' (duration: 124.018809ms)","trace[825913070] 'applied index is now lower than readState.Index' (duration: 91.766056ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:54.340156Z","caller":"traceutil/trace.go:171","msg":"trace[1670389575] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"234.410161ms","start":"2026-05-12T15:58:54.105732Z","end":"2026-05-12T15:58:54.340143Z","steps":["trace[1670389575] 'process raft request' (duration: 142.529031ms)","trace[1670389575] 'compare' (duration: 91.573962ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:54.340283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.602389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:54.340368Z","caller":"traceutil/trace.go:171","msg":"trace[1752581084] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25125; }","duration":"212.734971ms","start":"2026-05-12T15:58:54.127614Z","end":"2026-05-12T15:58:54.340349Z","steps":["trace[1752581084] 'agreement among raft nodes before linearized reading' (duration: 212.583288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:54.340358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.430146ms","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:6"} {"level":"info","ts":"2026-05-12T15:58:54.340476Z","caller":"traceutil/trace.go:171","msg":"trace[1801865381] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25125; }","duration":"243.620481ms","start":"2026-05-12T15:58:54.09683Z","end":"2026-05-12T15:58:54.340451Z","steps":["trace[1801865381] 'agreement among raft nodes before linearized reading' (duration: 243.366595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:54.340624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.911737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-12T15:58:54.34071Z","caller":"traceutil/trace.go:171","msg":"trace[1257374382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25125; }","duration":"155.04211ms","start":"2026-05-12T15:58:54.185646Z","end":"2026-05-12T15:58:54.340689Z","steps":["trace[1257374382] 'agreement among raft nodes before linearized reading' (duration: 154.886027ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:54.664083Z","caller":"traceutil/trace.go:171","msg":"trace[1959563656] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"318.657957ms","start":"2026-05-12T15:58:54.345383Z","end":"2026-05-12T15:58:54.664041Z","steps":["trace[1959563656] 'process raft request' (duration: 318.254047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:54.664285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:58:54.345372Z","time spent":"318.802441ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:58:54.664556Z","caller":"traceutil/trace.go:171","msg":"trace[879643046] linearizableReadLoop","detail":"{readStateIndex:25950; appliedIndex:25949; }","duration":"283.435914ms","start":"2026-05-12T15:58:54.381081Z","end":"2026-05-12T15:58:54.664517Z","steps":["trace[879643046] 'read index received' (duration: 282.48021ms)","trace[879643046] 'applied index is now lower than readState.Index' (duration: 913.693µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:54.664777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.677469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-05-12T15:58:54.664872Z","caller":"traceutil/trace.go:171","msg":"trace[871488934] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25127; }","duration":"283.796833ms","start":"2026-05-12T15:58:54.381061Z","end":"2026-05-12T15:58:54.664858Z","steps":["trace[871488934] 'agreement among raft nodes before linearized reading' (duration: 283.557997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:54.66497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.7194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f6ed4a7-b172-4c49-bb5a-3c897f993883\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-12T15:58:54.665041Z","caller":"traceutil/trace.go:171","msg":"trace[146124704] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f6ed4a7-b172-4c49-bb5a-3c897f993883; range_end:; response_count:1; response_revision:25127; }","duration":"181.838643ms","start":"2026-05-12T15:58:54.483186Z","end":"2026-05-12T15:58:54.665025Z","steps":["trace[146124704] 'agreement among raft nodes before linearized reading' (duration: 181.552406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:54.870956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.746475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T15:58:54.871026Z","caller":"traceutil/trace.go:171","msg":"trace[1668752733] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25127; }","duration":"101.886039ms","start":"2026-05-12T15:58:54.769123Z","end":"2026-05-12T15:58:54.871009Z","steps":["trace[1668752733] 'count revisions from in-memory index tree' (duration: 101.53728ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:55.045474Z","caller":"traceutil/trace.go:171","msg":"trace[272797948] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"269.83525ms","start":"2026-05-12T15:58:54.775582Z","end":"2026-05-12T15:58:55.045418Z","steps":["trace[272797948] 'process raft request' (duration: 269.592754ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:55.045654Z","caller":"traceutil/trace.go:171","msg":"trace[1115921082] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"269.756667ms","start":"2026-05-12T15:58:54.77588Z","end":"2026-05-12T15:58:55.045637Z","steps":["trace[1115921082] 'process raft request' (duration: 269.491821ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:55.045867Z","caller":"traceutil/trace.go:171","msg":"trace[1998492032] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"269.05682ms","start":"2026-05-12T15:58:54.776797Z","end":"2026-05-12T15:58:55.045854Z","steps":["trace[1998492032] 'process raft request' (duration: 268.780794ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:55.046076Z","caller":"traceutil/trace.go:171","msg":"trace[1506618670] linearizableReadLoop","detail":"{readStateIndex:25955; appliedIndex:25950; }","duration":"189.477685ms","start":"2026-05-12T15:58:54.856566Z","end":"2026-05-12T15:58:55.046043Z","steps":["trace[1506618670] 'read index received' (duration: 12.640163ms)","trace[1506618670] 'applied index is now lower than readState.Index' (duration: 176.835872ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:55.046148Z","caller":"traceutil/trace.go:171","msg":"trace[1422349057] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"269.233084ms","start":"2026-05-12T15:58:54.776895Z","end":"2026-05-12T15:58:55.046128Z","steps":["trace[1422349057] 'process raft request' (duration: 268.907997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:55.046314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.724971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-12T15:58:55.046357Z","caller":"traceutil/trace.go:171","msg":"trace[1109023904] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25132; }","duration":"189.821494ms","start":"2026-05-12T15:58:54.856523Z","end":"2026-05-12T15:58:55.046344Z","steps":["trace[1109023904] 'agreement among raft nodes before linearized reading' (duration: 189.639109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:55.413065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.925368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T15:58:55.413174Z","caller":"traceutil/trace.go:171","msg":"trace[1972198632] linearizableReadLoop","detail":"{readStateIndex:25956; appliedIndex:25955; }","duration":"277.966633ms","start":"2026-05-12T15:58:55.13519Z","end":"2026-05-12T15:58:55.413156Z","steps":["trace[1972198632] 'read index received' (duration: 140.865912ms)","trace[1972198632] 'applied index is now lower than readState.Index' (duration: 137.098951ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:55.413256Z","caller":"traceutil/trace.go:171","msg":"trace[818717909] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"278.064935ms","start":"2026-05-12T15:58:55.13512Z","end":"2026-05-12T15:58:55.413184Z","steps":["trace[818717909] 'process raft request' (duration: 140.923393ms)","trace[818717909] 'compare' (duration: 136.307322ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:55.413447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.25251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-12T15:58:55.41349Z","caller":"traceutil/trace.go:171","msg":"trace[488308041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25133; }","duration":"278.324221ms","start":"2026-05-12T15:58:55.135155Z","end":"2026-05-12T15:58:55.413479Z","steps":["trace[488308041] 'agreement among raft nodes before linearized reading' (duration: 278.076385ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:55.415093Z","caller":"traceutil/trace.go:171","msg":"trace[1492826294] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"241.840579ms","start":"2026-05-12T15:58:55.173231Z","end":"2026-05-12T15:58:55.415072Z","steps":["trace[1492826294] 'process raft request' (duration: 241.665555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:58:55.415152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.098627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T15:58:55.415235Z","caller":"traceutil/trace.go:171","msg":"trace[83602736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25134; }","duration":"278.224939ms","start":"2026-05-12T15:58:55.136976Z","end":"2026-05-12T15:58:55.415201Z","steps":["trace[83602736] 'agreement among raft nodes before linearized reading' (duration: 278.058105ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:55.644711Z","caller":"traceutil/trace.go:171","msg":"trace[207705511] linearizableReadLoop","detail":"{readStateIndex:25958; appliedIndex:25957; }","duration":"223.863368ms","start":"2026-05-12T15:58:55.420811Z","end":"2026-05-12T15:58:55.644675Z","steps":["trace[207705511] 'read index received' (duration: 136.808634ms)","trace[207705511] 'applied index is now lower than readState.Index' (duration: 87.053314ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:58:55.644743Z","caller":"traceutil/trace.go:171","msg":"trace[823465751] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"226.703917ms","start":"2026-05-12T15:58:55.417994Z","end":"2026-05-12T15:58:55.644698Z","steps":["trace[823465751] 'process raft request' (duration: 139.544971ms)","trace[823465751] 'compare' (duration: 86.955561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:58:55.645004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.083254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:58:55.64518Z","caller":"traceutil/trace.go:171","msg":"trace[1536421936] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:25135; }","duration":"224.395232ms","start":"2026-05-12T15:58:55.420757Z","end":"2026-05-12T15:58:55.645152Z","steps":["trace[1536421936] 'agreement among raft nodes before linearized reading' (duration: 224.045783ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:55.648581Z","caller":"traceutil/trace.go:171","msg":"trace[535750109] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"226.588653ms","start":"2026-05-12T15:58:55.42195Z","end":"2026-05-12T15:58:55.648539Z","steps":["trace[535750109] 'process raft request' (duration: 226.168814ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:55.648709Z","caller":"traceutil/trace.go:171","msg":"trace[158938475] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"165.572013ms","start":"2026-05-12T15:58:55.483121Z","end":"2026-05-12T15:58:55.648693Z","steps":["trace[158938475] 'process raft request' (duration: 165.194814ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:55.648934Z","caller":"traceutil/trace.go:171","msg":"trace[307114687] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"165.609114ms","start":"2026-05-12T15:58:55.483316Z","end":"2026-05-12T15:58:55.648925Z","steps":["trace[307114687] 'process raft request' (duration: 165.05293ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:58:57.537571Z","caller":"traceutil/trace.go:171","msg":"trace[135811667] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"118.169628ms","start":"2026-05-12T15:58:57.419376Z","end":"2026-05-12T15:58:57.537545Z","steps":["trace[135811667] 'process raft request' (duration: 117.999864ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:01.752142Z","caller":"traceutil/trace.go:171","msg":"trace[1546889521] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"193.333237ms","start":"2026-05-12T15:59:01.558781Z","end":"2026-05-12T15:59:01.752114Z","steps":["trace[1546889521] 'process raft request' (duration: 193.05347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:01.974141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.825685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T15:59:01.974385Z","caller":"traceutil/trace.go:171","msg":"trace[885954549] linearizableReadLoop","detail":"{readStateIndex:26026; appliedIndex:26024; }","duration":"303.465913ms","start":"2026-05-12T15:59:01.670897Z","end":"2026-05-12T15:59:01.974363Z","steps":["trace[885954549] 'read index received' (duration: 80.927137ms)","trace[885954549] 'applied index is now lower than readState.Index' (duration: 222.533566ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:01.974396Z","caller":"traceutil/trace.go:171","msg":"trace[1056980093] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"302.290116ms","start":"2026-05-12T15:59:01.672076Z","end":"2026-05-12T15:59:01.974367Z","steps":["trace[1056980093] 'process raft request' (duration: 302.195433ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:01.974416Z","caller":"traceutil/trace.go:171","msg":"trace[296281047] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"306.421495ms","start":"2026-05-12T15:59:01.667946Z","end":"2026-05-12T15:59:01.974368Z","steps":["trace[296281047] 'process raft request' (duration: 197.234791ms)","trace[296281047] 'compare' (duration: 108.655251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:59:01.974522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:01.672038Z","time spent":"302.409918ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:01.974593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:01.667914Z","time spent":"306.588408ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:01.974651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.760731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-12T15:59:01.974685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.11139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-12T15:59:01.974709Z","caller":"traceutil/trace.go:171","msg":"trace[1702004509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25202; }","duration":"299.15417ms","start":"2026-05-12T15:59:01.675549Z","end":"2026-05-12T15:59:01.974703Z","steps":["trace[1702004509] 'agreement among raft nodes before linearized reading' (duration: 299.065728ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:01.974699Z","caller":"traceutil/trace.go:171","msg":"trace[1900787823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25202; }","duration":"303.842912ms","start":"2026-05-12T15:59:01.67084Z","end":"2026-05-12T15:59:01.974683Z","steps":["trace[1900787823] 'agreement among raft nodes before linearized reading' (duration: 303.653538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:01.974705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.534319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T15:59:01.974764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:01.670826Z","time spent":"303.925115ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T15:59:01.974829Z","caller":"traceutil/trace.go:171","msg":"trace[225814014] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25202; }","duration":"177.734923ms","start":"2026-05-12T15:59:01.797052Z","end":"2026-05-12T15:59:01.974787Z","steps":["trace[225814014] 'agreement among raft nodes before linearized reading' (duration: 177.494058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:01.974757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.960068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T15:59:01.974891Z","caller":"traceutil/trace.go:171","msg":"trace[232199446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25202; }","duration":"302.145743ms","start":"2026-05-12T15:59:01.672728Z","end":"2026-05-12T15:59:01.974874Z","steps":["trace[232199446] 'agreement among raft nodes before linearized reading' (duration: 301.791564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:01.974953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:01.672701Z","time spent":"302.232275ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T15:59:25.068237Z","caller":"traceutil/trace.go:171","msg":"trace[328054063] linearizableReadLoop","detail":"{readStateIndex:26221; appliedIndex:26220; }","duration":"154.649931ms","start":"2026-05-12T15:59:24.913555Z","end":"2026-05-12T15:59:25.068205Z","steps":["trace[328054063] 'read index received' (duration: 154.559449ms)","trace[328054063] 'applied index is now lower than readState.Index' (duration: 88.842µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:59:25.068489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.903697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-05-12T15:59:25.068539Z","caller":"traceutil/trace.go:171","msg":"trace[1497646756] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25391; }","duration":"154.992829ms","start":"2026-05-12T15:59:24.913531Z","end":"2026-05-12T15:59:25.068524Z","steps":["trace[1497646756] 'agreement among raft nodes before linearized reading' (duration: 154.798885ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:25.068506Z","caller":"traceutil/trace.go:171","msg":"trace[217490169] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"157.466918ms","start":"2026-05-12T15:59:24.910972Z","end":"2026-05-12T15:59:25.068439Z","steps":["trace[217490169] 'process raft request' (duration: 157.057079ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:25.657724Z","caller":"traceutil/trace.go:171","msg":"trace[958813642] linearizableReadLoop","detail":"{readStateIndex:26227; appliedIndex:26226; }","duration":"184.65743ms","start":"2026-05-12T15:59:25.473038Z","end":"2026-05-12T15:59:25.657696Z","steps":["trace[958813642] 'read index received' (duration: 184.512837ms)","trace[958813642] 'applied index is now lower than readState.Index' (duration: 142.903µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:25.657903Z","caller":"traceutil/trace.go:171","msg":"trace[73432844] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"199.29968ms","start":"2026-05-12T15:59:25.458572Z","end":"2026-05-12T15:59:25.657871Z","steps":["trace[73432844] 'process raft request' (duration: 198.926282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:25.657977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.915186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-12T15:59:25.658024Z","caller":"traceutil/trace.go:171","msg":"trace[1078210305] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25397; }","duration":"185.009909ms","start":"2026-05-12T15:59:25.473002Z","end":"2026-05-12T15:59:25.658012Z","steps":["trace[1078210305] 'agreement among raft nodes before linearized reading' (duration: 184.799844ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:25.984337Z","caller":"traceutil/trace.go:171","msg":"trace[1704817090] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"130.788841ms","start":"2026-05-12T15:59:25.853523Z","end":"2026-05-12T15:59:25.984312Z","steps":["trace[1704817090] 'process raft request' (duration: 130.618147ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:26.445124Z","caller":"traceutil/trace.go:171","msg":"trace[252049089] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"277.273996ms","start":"2026-05-12T15:59:26.167805Z","end":"2026-05-12T15:59:26.445079Z","steps":["trace[252049089] 'process raft request' (duration: 269.380748ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:26.503045Z","caller":"traceutil/trace.go:171","msg":"trace[258875627] linearizableReadLoop","detail":"{readStateIndex:26233; appliedIndex:26230; }","duration":"215.087558ms","start":"2026-05-12T15:59:26.287878Z","end":"2026-05-12T15:59:26.502966Z","steps":["trace[258875627] 'read index received' (duration: 149.217882ms)","trace[258875627] 'applied index is now lower than readState.Index' (duration: 65.867746ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:59:26.503855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.296827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-12T15:59:26.503853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.940129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-12T15:59:26.503909Z","caller":"traceutil/trace.go:171","msg":"trace[1362775843] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25403; }","duration":"206.376929ms","start":"2026-05-12T15:59:26.297516Z","end":"2026-05-12T15:59:26.503893Z","steps":["trace[1362775843] 'agreement among raft nodes before linearized reading' (duration: 206.170944ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:26.503941Z","caller":"traceutil/trace.go:171","msg":"trace[156914127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25403; }","duration":"216.099613ms","start":"2026-05-12T15:59:26.287819Z","end":"2026-05-12T15:59:26.503919Z","steps":["trace[156914127] 'agreement among raft nodes before linearized reading' (duration: 215.811436ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:26.504144Z","caller":"traceutil/trace.go:171","msg":"trace[2095985876] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"335.318436ms","start":"2026-05-12T15:59:26.168813Z","end":"2026-05-12T15:59:26.504131Z","steps":["trace[2095985876] 'process raft request' (duration: 333.889442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:26.504242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:26.168794Z","time spent":"335.373767ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:26.504261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.664433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:59:26.504295Z","caller":"traceutil/trace.go:171","msg":"trace[1412994646] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25403; }","duration":"157.743856ms","start":"2026-05-12T15:59:26.34654Z","end":"2026-05-12T15:59:26.504284Z","steps":["trace[1412994646] 'agreement among raft nodes before linearized reading' (duration: 157.671134ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:26.504453Z","caller":"traceutil/trace.go:171","msg":"trace[448283325] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"335.414629ms","start":"2026-05-12T15:59:26.169026Z","end":"2026-05-12T15:59:26.50444Z","steps":["trace[448283325] 'process raft request' (duration: 333.873042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:26.504505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:26.169015Z","time spent":"335.46159ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:26.827454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.538133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T15:59:26.827582Z","caller":"traceutil/trace.go:171","msg":"trace[921496549] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"318.233828ms","start":"2026-05-12T15:59:26.509334Z","end":"2026-05-12T15:59:26.827568Z","steps":["trace[921496549] 'process raft request' (duration: 318.189387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:26.82762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:26.509312Z","time spent":"318.291338ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:59:26.827629Z","caller":"traceutil/trace.go:171","msg":"trace[1700571276] linearizableReadLoop","detail":"{readStateIndex:26234; appliedIndex:26233; }","duration":"318.391371ms","start":"2026-05-12T15:59:26.509213Z","end":"2026-05-12T15:59:26.827605Z","steps":["trace[1700571276] 'read index received' (duration: 111.660832ms)","trace[1700571276] 'applied index is now lower than readState.Index' (duration: 206.728588ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:26.827748Z","caller":"traceutil/trace.go:171","msg":"trace[1495617859] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"318.796281ms","start":"2026-05-12T15:59:26.508939Z","end":"2026-05-12T15:59:26.827736Z","steps":["trace[1495617859] 'process raft request' (duration: 111.917679ms)","trace[1495617859] 'compare' (duration: 206.456562ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:59:26.827844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:26.508913Z","time spent":"318.859931ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:26.827995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.77892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:59:26.828016Z","caller":"traceutil/trace.go:171","msg":"trace[2060345871] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25405; }","duration":"318.846352ms","start":"2026-05-12T15:59:26.509164Z","end":"2026-05-12T15:59:26.82801Z","steps":["trace[2060345871] 'agreement among raft nodes before linearized reading' (duration: 318.809781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:26.828031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:26.509142Z","time spent":"318.884922ms","remote":"127.0.0.1:42140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-05-12T15:59:27.380535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.10214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-12T15:59:27.38063Z","caller":"traceutil/trace.go:171","msg":"trace[1656765986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25405; }","duration":"307.210413ms","start":"2026-05-12T15:59:27.073397Z","end":"2026-05-12T15:59:27.380607Z","steps":["trace[1656765986] 'range keys from in-memory index tree' (duration: 306.881344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:27.38067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:27.073331Z","time spent":"307.327915ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T15:59:27.411253Z","caller":"traceutil/trace.go:171","msg":"trace[917376806] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"317.52985ms","start":"2026-05-12T15:59:27.093692Z","end":"2026-05-12T15:59:27.411222Z","steps":["trace[917376806] 'process raft request' (duration: 317.333596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:27.411462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:27.093677Z","time spent":"317.673633ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:59:27.780473Z","caller":"traceutil/trace.go:171","msg":"trace[825991935] linearizableReadLoop","detail":"{readStateIndex:26240; appliedIndex:26235; }","duration":"453.288209ms","start":"2026-05-12T15:59:27.327164Z","end":"2026-05-12T15:59:27.780452Z","steps":["trace[825991935] 'read index received' (duration: 83.847426ms)","trace[825991935] 'applied index is now lower than readState.Index' (duration: 369.440083ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:27.780696Z","caller":"traceutil/trace.go:171","msg":"trace[1434427147] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"566.647263ms","start":"2026-05-12T15:59:27.214041Z","end":"2026-05-12T15:59:27.780688Z","steps":["trace[1434427147] 'process raft request' (duration: 566.196212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:27.780824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:27.214012Z","time spent":"566.753915ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:59:27.780959Z","caller":"traceutil/trace.go:171","msg":"trace[1086050927] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"566.903359ms","start":"2026-05-12T15:59:27.214048Z","end":"2026-05-12T15:59:27.780951Z","steps":["trace[1086050927] 'process raft request' (duration: 566.294264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:27.780997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:27.214023Z","time spent":"566.955059ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:59:27.781081Z","caller":"traceutil/trace.go:171","msg":"trace[2028607749] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"567.011401ms","start":"2026-05-12T15:59:27.214064Z","end":"2026-05-12T15:59:27.781075Z","steps":["trace[2028607749] 'process raft request' (duration: 566.304014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:27.781119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:27.214036Z","time spent":"567.063892ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:59:27.781197Z","caller":"traceutil/trace.go:171","msg":"trace[1803314800] transaction","detail":"{read_only:false; response_revision:25410; number_of_response:1; }","duration":"564.881801ms","start":"2026-05-12T15:59:27.21631Z","end":"2026-05-12T15:59:27.781192Z","steps":["trace[1803314800] 'process raft request' (duration: 564.083152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:27.781235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:27.216297Z","time spent":"564.920691ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:59:27.781299Z","caller":"traceutil/trace.go:171","msg":"trace[422778470] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"396.333366ms","start":"2026-05-12T15:59:27.384961Z","end":"2026-05-12T15:59:27.781295Z","steps":["trace[422778470] 'process raft request' (duration: 395.460105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:27.781326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:27.384938Z","time spent":"396.374086ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:27.781544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.353395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:59:27.781623Z","caller":"traceutil/trace.go:171","msg":"trace[1365460677] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:25411; }","duration":"454.483777ms","start":"2026-05-12T15:59:27.327119Z","end":"2026-05-12T15:59:27.781602Z","steps":["trace[1365460677] 'agreement among raft nodes before linearized reading' (duration: 454.312803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:27.781673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:27.327107Z","time spent":"454.55566ms","remote":"127.0.0.1:53948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-05-12T15:59:27.78178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.091639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:59:27.781812Z","caller":"traceutil/trace.go:171","msg":"trace[1222961430] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25411; }","duration":"271.173931ms","start":"2026-05-12T15:59:27.510627Z","end":"2026-05-12T15:59:27.781801Z","steps":["trace[1222961430] 'agreement among raft nodes before linearized reading' (duration: 271.12627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:27.96834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.480504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-12T15:59:27.968403Z","caller":"traceutil/trace.go:171","msg":"trace[248498623] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25411; }","duration":"110.585067ms","start":"2026-05-12T15:59:27.857803Z","end":"2026-05-12T15:59:27.968388Z","steps":["trace[248498623] 'range keys from in-memory index tree' (duration: 110.28733ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:28.262138Z","caller":"traceutil/trace.go:171","msg":"trace[1149106619] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"101.730015ms","start":"2026-05-12T15:59:28.16038Z","end":"2026-05-12T15:59:28.26211Z","steps":["trace[1149106619] 'process raft request' (duration: 101.488589ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:28.40745Z","caller":"traceutil/trace.go:171","msg":"trace[1247813275] transaction","detail":"{read_only:false; response_revision:25414; number_of_response:1; }","duration":"238.820736ms","start":"2026-05-12T15:59:28.168587Z","end":"2026-05-12T15:59:28.407408Z","steps":["trace[1247813275] 'process raft request' (duration: 165.926571ms)","trace[1247813275] 'compare' (duration: 72.70131ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:28.836834Z","caller":"traceutil/trace.go:171","msg":"trace[2144249071] linearizableReadLoop","detail":"{readStateIndex:26249; appliedIndex:26248; }","duration":"101.776465ms","start":"2026-05-12T15:59:28.735036Z","end":"2026-05-12T15:59:28.836812Z","steps":["trace[2144249071] 'read index received' (duration: 76.654144ms)","trace[2144249071] 'applied index is now lower than readState.Index' (duration: 25.121541ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:28.836946Z","caller":"traceutil/trace.go:171","msg":"trace[1145567429] transaction","detail":"{read_only:false; response_revision:25418; number_of_response:1; }","duration":"244.84731ms","start":"2026-05-12T15:59:28.59209Z","end":"2026-05-12T15:59:28.836937Z","steps":["trace[1145567429] 'process raft request' (duration: 219.701498ms)","trace[1145567429] 'compare' (duration: 24.933007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:59:28.837058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.003062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:59:28.837163Z","caller":"traceutil/trace.go:171","msg":"trace[1183911965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25418; }","duration":"102.157275ms","start":"2026-05-12T15:59:28.734986Z","end":"2026-05-12T15:59:28.837144Z","steps":["trace[1183911965] 'agreement among raft nodes before linearized reading' (duration: 102.004442ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:30.007769Z","caller":"traceutil/trace.go:171","msg":"trace[1968257333] linearizableReadLoop","detail":"{readStateIndex:26254; appliedIndex:26251; }","duration":"219.76692ms","start":"2026-05-12T15:59:29.78798Z","end":"2026-05-12T15:59:30.007747Z","steps":["trace[1968257333] 'read index received' (duration: 47.148829ms)","trace[1968257333] 'applied index is now lower than readState.Index' (duration: 172.616861ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:30.007841Z","caller":"traceutil/trace.go:171","msg":"trace[2039413304] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"219.574034ms","start":"2026-05-12T15:59:29.788242Z","end":"2026-05-12T15:59:30.007816Z","steps":["trace[2039413304] 'process raft request' (duration: 219.465302ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:30.007827Z","caller":"traceutil/trace.go:171","msg":"trace[1787918169] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"219.847742ms","start":"2026-05-12T15:59:29.787941Z","end":"2026-05-12T15:59:30.007789Z","steps":["trace[1787918169] 'process raft request' (duration: 219.723629ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:30.008085Z","caller":"traceutil/trace.go:171","msg":"trace[1227108975] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"220.241931ms","start":"2026-05-12T15:59:29.787832Z","end":"2026-05-12T15:59:30.008074Z","steps":["trace[1227108975] 'process raft request' (duration: 219.688797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:30.008017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.012325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-12T15:59:30.008242Z","caller":"traceutil/trace.go:171","msg":"trace[1049227719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25424; }","duration":"220.278581ms","start":"2026-05-12T15:59:29.787944Z","end":"2026-05-12T15:59:30.008222Z","steps":["trace[1049227719] 'agreement among raft nodes before linearized reading' (duration: 219.934693ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:30.52595Z","caller":"traceutil/trace.go:171","msg":"trace[162963733] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"111.826017ms","start":"2026-05-12T15:59:30.414095Z","end":"2026-05-12T15:59:30.525921Z","steps":["trace[162963733] 'process raft request' (duration: 111.482079ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:30.589827Z","caller":"traceutil/trace.go:171","msg":"trace[1174808997] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"174.865776ms","start":"2026-05-12T15:59:30.414935Z","end":"2026-05-12T15:59:30.589801Z","steps":["trace[1174808997] 'process raft request' (duration: 174.714612ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:33.30084Z","caller":"traceutil/trace.go:171","msg":"trace[938812300] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"133.305201ms","start":"2026-05-12T15:59:33.167514Z","end":"2026-05-12T15:59:33.300819Z","steps":["trace[938812300] 'process raft request' (duration: 133.165357ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:34.839666Z","caller":"traceutil/trace.go:171","msg":"trace[304781834] linearizableReadLoop","detail":"{readStateIndex:26293; appliedIndex:26292; }","duration":"106.0873ms","start":"2026-05-12T15:59:34.733557Z","end":"2026-05-12T15:59:34.839644Z","steps":["trace[304781834] 'read index received' (duration: 105.998278ms)","trace[304781834] 'applied index is now lower than readState.Index' (duration: 87.952µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:34.839716Z","caller":"traceutil/trace.go:171","msg":"trace[1919673472] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"130.194726ms","start":"2026-05-12T15:59:34.709503Z","end":"2026-05-12T15:59:34.839697Z","steps":["trace[1919673472] 'process raft request' (duration: 129.988191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:34.839804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.229863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:59:34.839838Z","caller":"traceutil/trace.go:171","msg":"trace[1938460282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25461; }","duration":"106.310134ms","start":"2026-05-12T15:59:34.733518Z","end":"2026-05-12T15:59:34.839828Z","steps":["trace[1938460282] 'agreement among raft nodes before linearized reading' (duration: 106.235432ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:34.872071Z","caller":"traceutil/trace.go:171","msg":"trace[770078741] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"119.316206ms","start":"2026-05-12T15:59:34.752733Z","end":"2026-05-12T15:59:34.872049Z","steps":["trace[770078741] 'process raft request' (duration: 119.174551ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:34.996311Z","caller":"traceutil/trace.go:171","msg":"trace[888884394] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"120.75972ms","start":"2026-05-12T15:59:34.87552Z","end":"2026-05-12T15:59:34.99628Z","steps":["trace[888884394] 'process raft request' (duration: 64.254148ms)","trace[888884394] 'compare' (duration: 56.064822ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:47.029193Z","caller":"traceutil/trace.go:171","msg":"trace[1964387006] linearizableReadLoop","detail":"{readStateIndex:26385; appliedIndex:26384; }","duration":"125.816022ms","start":"2026-05-12T15:59:46.903351Z","end":"2026-05-12T15:59:47.029167Z","steps":["trace[1964387006] 'read index received' (duration: 125.592827ms)","trace[1964387006] 'applied index is now lower than readState.Index' (duration: 221.775µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:47.029267Z","caller":"traceutil/trace.go:171","msg":"trace[994554481] transaction","detail":"{read_only:false; response_revision:25551; number_of_response:1; }","duration":"126.897357ms","start":"2026-05-12T15:59:46.902333Z","end":"2026-05-12T15:59:47.02923Z","steps":["trace[994554481] 'process raft request' (duration: 126.657222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:47.029331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.955135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:59:47.029367Z","caller":"traceutil/trace.go:171","msg":"trace[994555857] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25551; }","duration":"126.061487ms","start":"2026-05-12T15:59:46.903294Z","end":"2026-05-12T15:59:47.029356Z","steps":["trace[994555857] 'agreement among raft nodes before linearized reading' (duration: 125.974885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:52.962821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.327293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T15:59:52.963257Z","caller":"traceutil/trace.go:171","msg":"trace[1715821530] transaction","detail":"{read_only:false; response_revision:25597; number_of_response:1; }","duration":"139.236352ms","start":"2026-05-12T15:59:52.823996Z","end":"2026-05-12T15:59:52.963232Z","steps":["trace[1715821530] 'process raft request' (duration: 138.934685ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:52.963324Z","caller":"traceutil/trace.go:171","msg":"trace[2009667760] transaction","detail":"{read_only:false; response_revision:25596; number_of_response:1; }","duration":"224.068383ms","start":"2026-05-12T15:59:52.73922Z","end":"2026-05-12T15:59:52.963289Z","steps":["trace[2009667760] 'process raft request' (duration: 112.158255ms)","trace[2009667760] 'compare' (duration: 110.882883ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:52.963392Z","caller":"traceutil/trace.go:171","msg":"trace[1376714507] linearizableReadLoop","detail":"{readStateIndex:26432; appliedIndex:26431; }","duration":"175.155453ms","start":"2026-05-12T15:59:52.788193Z","end":"2026-05-12T15:59:52.963348Z","steps":["trace[1376714507] 'read index received' (duration: 63.198253ms)","trace[1376714507] 'applied index is now lower than readState.Index' (duration: 111.9519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:59:52.963579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.359678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:59:52.963679Z","caller":"traceutil/trace.go:171","msg":"trace[151395060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25597; }","duration":"175.516502ms","start":"2026-05-12T15:59:52.788137Z","end":"2026-05-12T15:59:52.963653Z","steps":["trace[151395060] 'agreement among raft nodes before linearized reading' (duration: 175.334278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:53.229153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.107109ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-12T15:59:53.229333Z","caller":"traceutil/trace.go:171","msg":"trace[547173923] linearizableReadLoop","detail":"{readStateIndex:26434; appliedIndex:26433; }","duration":"165.695156ms","start":"2026-05-12T15:59:53.063613Z","end":"2026-05-12T15:59:53.229308Z","steps":["trace[547173923] 'read index received' (duration: 21.326491ms)","trace[547173923] 'applied index is now lower than readState.Index' (duration: 144.366375ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:59:53.22983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.564669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-12T15:59:53.229927Z","caller":"traceutil/trace.go:171","msg":"trace[771991906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25597; }","duration":"141.702162ms","start":"2026-05-12T15:59:53.088202Z","end":"2026-05-12T15:59:53.229904Z","steps":["trace[771991906] 'agreement among raft nodes before linearized reading' (duration: 141.429645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:53.229971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.921464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-12T15:59:53.229981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.322991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-12T15:59:53.230028Z","caller":"traceutil/trace.go:171","msg":"trace[636462448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25597; }","duration":"103.030076ms","start":"2026-05-12T15:59:53.126982Z","end":"2026-05-12T15:59:53.230012Z","steps":["trace[636462448] 'agreement among raft nodes before linearized reading' (duration: 102.800431ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:53.230089Z","caller":"traceutil/trace.go:171","msg":"trace[120922719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25597; }","duration":"166.480285ms","start":"2026-05-12T15:59:53.06358Z","end":"2026-05-12T15:59:53.23006Z","steps":["trace[120922719] 'agreement among raft nodes before linearized reading' (duration: 166.141777ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:53.602507Z","caller":"traceutil/trace.go:171","msg":"trace[1653437293] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"366.112603ms","start":"2026-05-12T15:59:53.236363Z","end":"2026-05-12T15:59:53.602476Z","steps":["trace[1653437293] 'process raft request' (duration: 365.98129ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:53.602583Z","caller":"traceutil/trace.go:171","msg":"trace[729520396] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"368.257395ms","start":"2026-05-12T15:59:53.234294Z","end":"2026-05-12T15:59:53.602552Z","steps":["trace[729520396] 'process raft request' (duration: 335.420819ms)","trace[729520396] 'compare' (duration: 32.460147ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:59:53.602701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.234268Z","time spent":"368.360438ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:59:53.602719Z","caller":"traceutil/trace.go:171","msg":"trace[724401477] linearizableReadLoop","detail":"{readStateIndex:26439; appliedIndex:26435; }","duration":"348.876861ms","start":"2026-05-12T15:59:53.253824Z","end":"2026-05-12T15:59:53.602701Z","steps":["trace[724401477] 'read index received' (duration: 315.907471ms)","trace[724401477] 'applied index is now lower than readState.Index' (duration: 32.968809ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:53.602793Z","caller":"traceutil/trace.go:171","msg":"trace[1101154186] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"366.188425ms","start":"2026-05-12T15:59:53.236528Z","end":"2026-05-12T15:59:53.602717Z","steps":["trace[1101154186] 'process raft request' (duration: 365.895818ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:53.602889Z","caller":"traceutil/trace.go:171","msg":"trace[2098293181] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"365.175411ms","start":"2026-05-12T15:59:53.237705Z","end":"2026-05-12T15:59:53.602881Z","steps":["trace[2098293181] 'process raft request' (duration: 364.925335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:53.60295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.237684Z","time spent":"365.223282ms","remote":"127.0.0.1:42132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:53.602854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.017875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-05-12T15:59:53.603038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.23652Z","time spent":"366.391419ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:59:53.603053Z","caller":"traceutil/trace.go:171","msg":"trace[1338071123] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25602; }","duration":"349.254229ms","start":"2026-05-12T15:59:53.253782Z","end":"2026-05-12T15:59:53.603036Z","steps":["trace[1338071123] 'agreement among raft nodes before linearized reading' (duration: 348.990803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:53.602703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.236337Z","time spent":"366.260517ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:53.603104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.253768Z","time spent":"349.322162ms","remote":"127.0.0.1:42120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-05-12T15:59:53.86921Z","caller":"traceutil/trace.go:171","msg":"trace[1612487620] linearizableReadLoop","detail":"{readStateIndex:26440; appliedIndex:26439; }","duration":"263.890516ms","start":"2026-05-12T15:59:53.60529Z","end":"2026-05-12T15:59:53.86918Z","steps":["trace[1612487620] 'read index received' (duration: 165.706206ms)","trace[1612487620] 'applied index is now lower than readState.Index' (duration: 98.183189ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T15:59:53.869351Z","caller":"traceutil/trace.go:171","msg":"trace[1141190725] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"311.213708ms","start":"2026-05-12T15:59:53.558127Z","end":"2026-05-12T15:59:53.869341Z","steps":["trace[1141190725] 'process raft request' (duration: 212.720501ms)","trace[1141190725] 'compare' (duration: 98.157079ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:59:53.869507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.558099Z","time spent":"311.27943ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:53.869556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.244514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-12T15:59:53.869671Z","caller":"traceutil/trace.go:171","msg":"trace[1339280678] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:25603; }","duration":"264.414268ms","start":"2026-05-12T15:59:53.60523Z","end":"2026-05-12T15:59:53.869644Z","steps":["trace[1339280678] 'agreement among raft nodes before linearized reading' (duration: 264.103351ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:54.013722Z","caller":"traceutil/trace.go:171","msg":"trace[1463186347] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"404.684936ms","start":"2026-05-12T15:59:53.609007Z","end":"2026-05-12T15:59:54.013692Z","steps":["trace[1463186347] 'process raft request' (duration: 404.41957ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:54.013799Z","caller":"traceutil/trace.go:171","msg":"trace[2001146493] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"403.707963ms","start":"2026-05-12T15:59:53.610053Z","end":"2026-05-12T15:59:54.013761Z","steps":["trace[2001146493] 'process raft request' (duration: 403.550439ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:54.013813Z","caller":"traceutil/trace.go:171","msg":"trace[346998234] linearizableReadLoop","detail":"{readStateIndex:26444; appliedIndex:26440; }","duration":"144.494669ms","start":"2026-05-12T15:59:53.869292Z","end":"2026-05-12T15:59:54.013786Z","steps":["trace[346998234] 'read index received' (duration: 144.072628ms)","trace[346998234] 'applied index is now lower than readState.Index' (duration: 421.161µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T15:59:54.013882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.608979Z","time spent":"404.81694ms","remote":"127.0.0.1:42228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1205,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T15:59:54.013922Z","caller":"traceutil/trace.go:171","msg":"trace[1971501182] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"317.865519ms","start":"2026-05-12T15:59:53.69602Z","end":"2026-05-12T15:59:54.013886Z","steps":["trace[1971501182] 'process raft request' (duration: 317.695654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:54.013963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.610037Z","time spent":"403.835766ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:54.014007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.383668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:59:54.01407Z","caller":"traceutil/trace.go:171","msg":"trace[1658668625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25607; }","duration":"279.511871ms","start":"2026-05-12T15:59:53.734541Z","end":"2026-05-12T15:59:54.014053Z","steps":["trace[1658668625] 'agreement among raft nodes before linearized reading' (duration: 279.391588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:54.014095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.695991Z","time spent":"318.016723ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:54.014128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.545975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T15:59:54.014165Z","caller":"traceutil/trace.go:171","msg":"trace[2104467455] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25607; }","duration":"341.630817ms","start":"2026-05-12T15:59:53.67252Z","end":"2026-05-12T15:59:54.014151Z","steps":["trace[2104467455] 'agreement among raft nodes before linearized reading' (duration: 341.562585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:54.014206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.672493Z","time spent":"341.704689ms","remote":"127.0.0.1:37932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "} {"level":"warn","ts":"2026-05-12T15:59:54.0142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.518951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-12T15:59:54.014267Z","caller":"traceutil/trace.go:171","msg":"trace[1673619558] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"404.00698ms","start":"2026-05-12T15:59:53.610247Z","end":"2026-05-12T15:59:54.014254Z","steps":["trace[1673619558] 'process raft request' (duration: 403.418295ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:54.014318Z","caller":"traceutil/trace.go:171","msg":"trace[1924904510] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25607; }","duration":"142.672165ms","start":"2026-05-12T15:59:53.871617Z","end":"2026-05-12T15:59:54.014289Z","steps":["trace[1924904510] 'agreement among raft nodes before linearized reading' (duration: 142.382218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T15:59:54.014336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T15:59:53.610216Z","time spent":"404.084232ms","remote":"127.0.0.1:42120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":675,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T15:59:54.014422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.332207ms","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:6"} {"level":"info","ts":"2026-05-12T15:59:54.014569Z","caller":"traceutil/trace.go:171","msg":"trace[605461283] 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:25607; }","duration":"114.550952ms","start":"2026-05-12T15:59:53.899988Z","end":"2026-05-12T15:59:54.014539Z","steps":["trace[605461283] 'agreement among raft nodes before linearized reading' (duration: 114.254275ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:54.235491Z","caller":"traceutil/trace.go:171","msg":"trace[1992640072] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"212.810224ms","start":"2026-05-12T15:59:54.022659Z","end":"2026-05-12T15:59:54.23547Z","steps":["trace[1992640072] 'process raft request' (duration: 211.640186ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:54.2379Z","caller":"traceutil/trace.go:171","msg":"trace[1380711840] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"211.442961ms","start":"2026-05-12T15:59:54.026415Z","end":"2026-05-12T15:59:54.237858Z","steps":["trace[1380711840] 'process raft request' (duration: 210.896908ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:54.23818Z","caller":"traceutil/trace.go:171","msg":"trace[227130907] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"138.181768ms","start":"2026-05-12T15:59:54.099983Z","end":"2026-05-12T15:59:54.238165Z","steps":["trace[227130907] 'process raft request' (duration: 137.576263ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:54.238296Z","caller":"traceutil/trace.go:171","msg":"trace[1964496548] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"138.24089ms","start":"2026-05-12T15:59:54.100046Z","end":"2026-05-12T15:59:54.238286Z","steps":["trace[1964496548] 'process raft request' (duration: 137.571753ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T15:59:54.238389Z","caller":"traceutil/trace.go:171","msg":"trace[1761995483] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"132.763219ms","start":"2026-05-12T15:59:54.105604Z","end":"2026-05-12T15:59:54.238367Z","steps":["trace[1761995483] 'process raft request' (duration: 132.098632ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:00:00.405765Z","caller":"traceutil/trace.go:171","msg":"trace[182976743] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"182.925218ms","start":"2026-05-12T16:00:00.222807Z","end":"2026-05-12T16:00:00.405732Z","steps":["trace[182976743] 'process raft request' (duration: 182.726694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.700236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.239225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T16:00:00.700415Z","caller":"traceutil/trace.go:171","msg":"trace[1186839585] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"440.437682ms","start":"2026-05-12T16:00:00.259948Z","end":"2026-05-12T16:00:00.700386Z","steps":["trace[1186839585] 'process raft request' (duration: 251.955001ms)","trace[1186839585] 'compare' (duration: 187.932708ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:00:00.700459Z","caller":"traceutil/trace.go:171","msg":"trace[490277591] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"439.337634ms","start":"2026-05-12T16:00:00.26111Z","end":"2026-05-12T16:00:00.700447Z","steps":["trace[490277591] 'process raft request' (duration: 439.272983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.700508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:00:00.261092Z","time spent":"439.388046ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:00:00.700527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:00:00.259904Z","time spent":"440.571585ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:00:00.700631Z","caller":"traceutil/trace.go:171","msg":"trace[1076282915] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"439.701963ms","start":"2026-05-12T16:00:00.260912Z","end":"2026-05-12T16:00:00.700614Z","steps":["trace[1076282915] 'process raft request' (duration: 439.410676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.700677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:00:00.260887Z","time spent":"439.769056ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:00:00.700927Z","caller":"traceutil/trace.go:171","msg":"trace[1152076202] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"437.724838ms","start":"2026-05-12T16:00:00.263168Z","end":"2026-05-12T16:00:00.700893Z","steps":["trace[1152076202] 'process raft request' (duration: 437.591595ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:00:00.700936Z","caller":"traceutil/trace.go:171","msg":"trace[1328741756] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"439.650793ms","start":"2026-05-12T16:00:00.261241Z","end":"2026-05-12T16:00:00.700891Z","steps":["trace[1328741756] 'process raft request' (duration: 439.16728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.701054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:00:00.263157Z","time spent":"437.82541ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:00:00.701019Z","caller":"traceutil/trace.go:171","msg":"trace[38882328] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"231.889371ms","start":"2026-05-12T16:00:00.469093Z","end":"2026-05-12T16:00:00.700983Z","steps":["trace[38882328] 'process raft request' (duration: 231.83899ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:00:00.70097Z","caller":"traceutil/trace.go:171","msg":"trace[921659098] linearizableReadLoop","detail":"{readStateIndex:26662; appliedIndex:26657; }","duration":"435.053644ms","start":"2026-05-12T16:00:00.265885Z","end":"2026-05-12T16:00:00.700939Z","steps":["trace[921659098] 'read index received' (duration: 140.611265ms)","trace[921659098] 'applied index is now lower than readState.Index' (duration: 294.439709ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:00:00.701116Z","caller":"traceutil/trace.go:171","msg":"trace[110638430] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"235.414885ms","start":"2026-05-12T16:00:00.465618Z","end":"2026-05-12T16:00:00.701032Z","steps":["trace[110638430] 'process raft request' (duration: 235.273922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.701149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:00:00.261223Z","time spent":"439.821797ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:00:00.701251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.355321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-engine-cleaner\" ","response":"range_response_count:1 size:541"} {"level":"warn","ts":"2026-05-12T16:00:00.701284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.632853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T16:00:00.701313Z","caller":"traceutil/trace.go:171","msg":"trace[1249267272] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25825; }","duration":"200.712454ms","start":"2026-05-12T16:00:00.500592Z","end":"2026-05-12T16:00:00.701304Z","steps":["trace[1249267272] 'agreement among raft nodes before linearized reading' (duration: 200.656643ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:00:00.701323Z","caller":"traceutil/trace.go:171","msg":"trace[1941679294] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-engine-cleaner; range_end:; response_count:1; response_revision:25825; }","duration":"435.442382ms","start":"2026-05-12T16:00:00.265861Z","end":"2026-05-12T16:00:00.701304Z","steps":["trace[1941679294] 'agreement among raft nodes before linearized reading' (duration: 435.160086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.701352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.075157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29643360-rmvkw\" ","response":"range_response_count:1 size:5505"} {"level":"warn","ts":"2026-05-12T16:00:00.701378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:00:00.265853Z","time spent":"435.512645ms","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":563,"request content":"key:\"/registry/serviceaccounts/openstack/heat-engine-cleaner\" "} {"level":"info","ts":"2026-05-12T16:00:00.701396Z","caller":"traceutil/trace.go:171","msg":"trace[655022192] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29643360-rmvkw; range_end:; response_count:1; response_revision:25825; }","duration":"291.152349ms","start":"2026-05-12T16:00:00.410232Z","end":"2026-05-12T16:00:00.701384Z","steps":["trace[655022192] 'agreement among raft nodes before linearized reading' (duration: 290.985945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.701381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.174025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T16:00:00.701481Z","caller":"traceutil/trace.go:171","msg":"trace[729364859] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25825; }","duration":"412.308419ms","start":"2026-05-12T16:00:00.289151Z","end":"2026-05-12T16:00:00.701459Z","steps":["trace[729364859] 'agreement among raft nodes before linearized reading' (duration: 412.145195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.701528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.508005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-service-cleaner\" ","response":"range_response_count:1 size:541"} {"level":"warn","ts":"2026-05-12T16:00:00.701586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.990059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-cell-setup-cron\" ","response":"range_response_count:1 size:541"} {"level":"warn","ts":"2026-05-12T16:00:00.701537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:00:00.289137Z","time spent":"412.386281ms","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-12T16:00:00.701642Z","caller":"traceutil/trace.go:171","msg":"trace[285378645] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-cell-setup-cron; range_end:; response_count:1; response_revision:25825; }","duration":"330.103071ms","start":"2026-05-12T16:00:00.371527Z","end":"2026-05-12T16:00:00.70163Z","steps":["trace[285378645] 'agreement among raft nodes before linearized reading' (duration: 329.726442ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:00:00.701595Z","caller":"traceutil/trace.go:171","msg":"trace[738494218] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-service-cleaner; range_end:; response_count:1; response_revision:25825; }","duration":"331.613788ms","start":"2026-05-12T16:00:00.369965Z","end":"2026-05-12T16:00:00.701579Z","steps":["trace[738494218] 'agreement among raft nodes before linearized reading' (duration: 331.301441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.701681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:00:00.371499Z","time spent":"330.174293ms","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":563,"request content":"key:\"/registry/serviceaccounts/openstack/nova-cell-setup-cron\" "} {"level":"warn","ts":"2026-05-12T16:00:00.701689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:00:00.369943Z","time spent":"331.73385ms","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":563,"request content":"key:\"/registry/serviceaccounts/openstack/nova-service-cleaner\" "} {"level":"warn","ts":"2026-05-12T16:00:00.701706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.769471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-12T16:00:00.701765Z","caller":"traceutil/trace.go:171","msg":"trace[1440515604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25825; }","duration":"435.838812ms","start":"2026-05-12T16:00:00.265909Z","end":"2026-05-12T16:00:00.701748Z","steps":["trace[1440515604] 'agreement among raft nodes before linearized reading' (duration: 435.296629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.701808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:00:00.265906Z","time spent":"435.893313ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T16:00:00.853279Z","caller":"traceutil/trace.go:171","msg":"trace[1157804977] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"142.156293ms","start":"2026-05-12T16:00:00.711099Z","end":"2026-05-12T16:00:00.853256Z","steps":["trace[1157804977] 'process raft request' (duration: 141.982259ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:00:00.853379Z","caller":"traceutil/trace.go:171","msg":"trace[954340481] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"142.823739ms","start":"2026-05-12T16:00:00.710491Z","end":"2026-05-12T16:00:00.853315Z","steps":["trace[954340481] 'process raft request' (duration: 142.338227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:00:00.853631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.808013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T16:00:00.853733Z","caller":"traceutil/trace.go:171","msg":"trace[387977860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25828; }","duration":"118.941456ms","start":"2026-05-12T16:00:00.734753Z","end":"2026-05-12T16:00:00.853694Z","steps":["trace[387977860] 'agreement among raft nodes before linearized reading' (duration: 118.810103ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:00:00.853196Z","caller":"traceutil/trace.go:171","msg":"trace[338954880] linearizableReadLoop","detail":"{readStateIndex:26667; appliedIndex:26664; }","duration":"118.366923ms","start":"2026-05-12T16:00:00.734799Z","end":"2026-05-12T16:00:00.853166Z","steps":["trace[338954880] 'read index received' (duration: 27.076758ms)","trace[338954880] 'applied index is now lower than readState.Index' (duration: 91.288375ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:00:09.931099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22894} {"level":"info","ts":"2026-05-12T16:00:10.070077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22894,"took":"132.132833ms","hash":1813733960,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":35540992,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-12T16:00:10.07017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1813733960,"revision":22894,"compact-revision":20012} {"level":"info","ts":"2026-05-12T16:00:56.957434Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a5d.wal"} {"level":"info","ts":"2026-05-12T16:05:09.943513Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25958} {"level":"info","ts":"2026-05-12T16:05:10.086539Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25958,"took":"138.671127ms","hash":48666501,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":35307520,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-12T16:05:10.086599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":48666501,"revision":25958,"compact-revision":22894} {"level":"info","ts":"2026-05-12T16:05:56.558421Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T16:05:56.563248Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-12T16:05:56.563329Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-12T16:10:09.950319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28711} {"level":"info","ts":"2026-05-12T16:10:10.100448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28711,"took":"144.35514ms","hash":3353434943,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":30425088,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T16:10:10.100552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3353434943,"revision":28711,"compact-revision":25958} {"level":"info","ts":"2026-05-12T16:15:09.960999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30946} {"level":"info","ts":"2026-05-12T16:15:10.076057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30946,"took":"111.449864ms","hash":1210795729,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-12T16:15:10.076132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1210795729,"revision":30946,"compact-revision":28711} {"level":"info","ts":"2026-05-12T16:20:09.969248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33209} {"level":"info","ts":"2026-05-12T16:20:10.126685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33209,"took":"150.66288ms","hash":508630775,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":32100352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T16:20:10.126741Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":508630775,"revision":33209,"compact-revision":30946} {"level":"info","ts":"2026-05-12T16:25:09.975621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35511} {"level":"info","ts":"2026-05-12T16:25:10.113972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35511,"took":"134.022731ms","hash":2367540825,"current-db-size-bytes":59740160,"current-db-size":"60 MB","current-db-size-in-use-bytes":31526912,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T16:25:10.114043Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2367540825,"revision":35511,"compact-revision":33209} {"level":"info","ts":"2026-05-12T16:27:26.002297Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T16:27:26.007176Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-12T16:27:26.007265Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-12T16:30:09.981243Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37739} {"level":"info","ts":"2026-05-12T16:30:10.105064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37739,"took":"119.415182ms","hash":1842400330,"current-db-size-bytes":59740160,"current-db-size":"60 MB","current-db-size-in-use-bytes":30547968,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T16:30:10.105146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1842400330,"revision":37739,"compact-revision":35511}