{"level":"warn","ts":"2026-05-05T22:14:27.854555Z","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-05T22:14:27.854667Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-05T22:14:27.854748Z","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-05T22:14:27.854764Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-05T22:14:27.854792Z","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-05T22:14:27.855629Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-05-05T22:14:27.855734Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-05T22:14:27.993863Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"137.837634ms"} {"level":"info","ts":"2026-05-05T22:14:28.024807Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-05-05T22:14:28.025037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T22:14:28.025074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-05-05T22:14:28.025294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T22:14:28.025339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-05-05T22:14:28.025446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-05-05T22:14:28.031452Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T22:14:28.03534Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T22:14:28.036848Z","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-05T22:14:28.040562Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T22:14:28.041382Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T22:14:28.041527Z","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-05T22:14:28.041832Z","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-05T22:14:28.041896Z","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-05T22:14:28.04254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-05-05T22:14:28.042704Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-05T22:14:28.044174Z","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-05T22:14:28.044419Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T22:14:28.044462Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T22:14:28.044991Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-05T22:14:28.045019Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-05T22:14:28.726744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T22:14:28.726842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T22:14:28.7269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-05-05T22:14:28.726923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-05-05T22:14:28.726934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-05T22:14:28.726952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-05-05T22:14:28.726967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-05T22:14:28.728572Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T22:14:28.72861Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:14:28.728688Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:14:28.728758Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:14:28.728961Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T22:14:28.729049Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T22:14:28.730002Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:14:28.730135Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:14:28.730167Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:14:28.73264Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-05-05T22:14:28.733017Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-05T22:14:40.365976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.936935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:14:40.366194Z","caller":"traceutil/trace.go:171","msg":"trace[1676750735] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"158.224551ms","start":"2026-05-05T22:14:40.207953Z","end":"2026-05-05T22:14:40.366178Z","steps":["trace[1676750735] 'process raft request' (duration: 158.150229ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:14:40.366155Z","caller":"traceutil/trace.go:171","msg":"trace[1669750954] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"274.428616ms","start":"2026-05-05T22:14:40.091692Z","end":"2026-05-05T22:14:40.366121Z","steps":["trace[1669750954] 'process raft request' (duration: 130.854306ms)","trace[1669750954] 'compare' (duration: 142.812052ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:14:40.36629Z","caller":"traceutil/trace.go:171","msg":"trace[1195403937] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"195.796312ms","start":"2026-05-05T22:14:40.170446Z","end":"2026-05-05T22:14:40.366243Z","steps":["trace[1195403937] 'read index received' (duration: 52.071098ms)","trace[1195403937] 'applied index is now lower than readState.Index' (duration: 143.719554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:14:40.36649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.008467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-05T22:14:40.366498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.668494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T22:14:40.366533Z","caller":"traceutil/trace.go:171","msg":"trace[1195227361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:394; }","duration":"196.13012ms","start":"2026-05-05T22:14:40.170392Z","end":"2026-05-05T22:14:40.366522Z","steps":["trace[1195227361] 'agreement among raft nodes before linearized reading' (duration: 195.955366ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:14:40.366546Z","caller":"traceutil/trace.go:171","msg":"trace[1449671833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:394; }","duration":"103.758676ms","start":"2026-05-05T22:14:40.262776Z","end":"2026-05-05T22:14:40.366534Z","steps":["trace[1449671833] 'agreement among raft nodes before linearized reading' (duration: 103.658644ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:14:40.634832Z","caller":"traceutil/trace.go:171","msg":"trace[1556409395] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:405; }","duration":"242.137642ms","start":"2026-05-05T22:14:40.392639Z","end":"2026-05-05T22:14:40.634776Z","steps":["trace[1556409395] 'read index received' (duration: 198.995868ms)","trace[1556409395] 'applied index is now lower than readState.Index' (duration: 43.140424ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:14:40.634864Z","caller":"traceutil/trace.go:171","msg":"trace[1085534448] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"264.323254ms","start":"2026-05-05T22:14:40.370496Z","end":"2026-05-05T22:14:40.634819Z","steps":["trace[1085534448] 'process raft request' (duration: 220.977795ms)","trace[1085534448] 'compare' (duration: 43.054652ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:14:40.634975Z","caller":"traceutil/trace.go:171","msg":"trace[2122386984] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"263.826532ms","start":"2026-05-05T22:14:40.371111Z","end":"2026-05-05T22:14:40.634938Z","steps":["trace[2122386984] 'process raft request' (duration: 263.599486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:14:40.635085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.406589ms","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-05T22:14:40.635154Z","caller":"traceutil/trace.go:171","msg":"trace[2123579645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"242.543751ms","start":"2026-05-05T22:14:40.392591Z","end":"2026-05-05T22:14:40.635135Z","steps":["trace[2123579645] 'agreement among raft nodes before linearized reading' (duration: 242.392178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:14:40.635168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.951728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-gghwz\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-05-05T22:14:40.635258Z","caller":"traceutil/trace.go:171","msg":"trace[160306584] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-gghwz; range_end:; response_count:1; response_revision:396; }","duration":"116.131853ms","start":"2026-05-05T22:14:40.519111Z","end":"2026-05-05T22:14:40.635243Z","steps":["trace[160306584] 'agreement among raft nodes before linearized reading' (duration: 115.919648ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:14:58.880113Z","caller":"traceutil/trace.go:171","msg":"trace[488370259] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"337.856027ms","start":"2026-05-05T22:14:58.542223Z","end":"2026-05-05T22:14:58.880079Z","steps":["trace[488370259] 'read index received' (duration: 306.67625ms)","trace[488370259] 'applied index is now lower than readState.Index' (duration: 31.178397ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:14:58.88026Z","caller":"traceutil/trace.go:171","msg":"trace[2134745338] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"360.906479ms","start":"2026-05-05T22:14:58.519337Z","end":"2026-05-05T22:14:58.880243Z","steps":["trace[2134745338] 'process raft request' (duration: 329.545058ms)","trace[2134745338] 'compare' (duration: 31.012543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:14:58.880575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.310828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:14:58.880625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:14:58.519208Z","time spent":"361.092313ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:14:58.88069Z","caller":"traceutil/trace.go:171","msg":"trace[917022561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"338.487472ms","start":"2026-05-05T22:14:58.542182Z","end":"2026-05-05T22:14:58.880669Z","steps":["trace[917022561] 'agreement among raft nodes before linearized reading' (duration: 338.099663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:14:58.8809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:14:58.542165Z","time spent":"338.663046ms","remote":"127.0.0.1:35448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T22:14:58.881336Z","caller":"traceutil/trace.go:171","msg":"trace[1443482936] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"333.387869ms","start":"2026-05-05T22:14:58.547919Z","end":"2026-05-05T22:14:58.881307Z","steps":["trace[1443482936] 'process raft request' (duration: 333.131103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:14:58.881503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:14:58.54787Z","time spent":"333.546364ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:14:58.881694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.32098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T22:14:58.881767Z","caller":"traceutil/trace.go:171","msg":"trace[647440442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"162.433872ms","start":"2026-05-05T22:14:58.719313Z","end":"2026-05-05T22:14:58.881747Z","steps":["trace[647440442] 'agreement among raft nodes before linearized reading' (duration: 162.151406ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:14:58.881752Z","caller":"traceutil/trace.go:171","msg":"trace[1215857592] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"312.971801ms","start":"2026-05-05T22:14:58.568752Z","end":"2026-05-05T22:14:58.881723Z","steps":["trace[1215857592] 'process raft request' (duration: 312.488808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:14:58.881915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:14:58.568716Z","time spent":"313.110364ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:14:58.881965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.510889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T22:14:58.882125Z","caller":"traceutil/trace.go:171","msg":"trace[1121142882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"153.726684ms","start":"2026-05-05T22:14:58.72836Z","end":"2026-05-05T22:14:58.882087Z","steps":["trace[1121142882] 'agreement among raft nodes before linearized reading' (duration: 153.408747ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:04.183477Z","caller":"traceutil/trace.go:171","msg":"trace[1687166787] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"256.371673ms","start":"2026-05-05T22:15:03.927051Z","end":"2026-05-05T22:15:04.183423Z","steps":["trace[1687166787] 'process raft request' (duration: 255.753168ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:04.723365Z","caller":"traceutil/trace.go:171","msg":"trace[2032482084] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"133.9542ms","start":"2026-05-05T22:15:04.589378Z","end":"2026-05-05T22:15:04.723332Z","steps":["trace[2032482084] 'process raft request' (duration: 133.758445ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:04.806536Z","caller":"traceutil/trace.go:171","msg":"trace[659326155] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"153.770115ms","start":"2026-05-05T22:15:04.652731Z","end":"2026-05-05T22:15:04.806501Z","steps":["trace[659326155] 'process raft request' (duration: 152.891394ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:05.27816Z","caller":"traceutil/trace.go:171","msg":"trace[78934199] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"369.496445ms","start":"2026-05-05T22:15:04.908639Z","end":"2026-05-05T22:15:05.278135Z","steps":["trace[78934199] 'read index received' (duration: 369.248649ms)","trace[78934199] 'applied index is now lower than readState.Index' (duration: 246.746µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:05.27823Z","caller":"traceutil/trace.go:171","msg":"trace[1289812159] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"412.015594ms","start":"2026-05-05T22:15:04.866178Z","end":"2026-05-05T22:15:05.278193Z","steps":["trace[1289812159] 'process raft request' (duration: 411.787958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:05.278378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:04.866146Z","time spent":"412.162777ms","remote":"127.0.0.1:35922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:15:05.278423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.289714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T22:15:05.278423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.768051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T22:15:05.278481Z","caller":"traceutil/trace.go:171","msg":"trace[2060394224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"365.392166ms","start":"2026-05-05T22:15:04.91307Z","end":"2026-05-05T22:15:05.278462Z","steps":["trace[2060394224] 'agreement among raft nodes before linearized reading' (duration: 365.238202ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:05.27849Z","caller":"traceutil/trace.go:171","msg":"trace[849998101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"369.851163ms","start":"2026-05-05T22:15:04.908622Z","end":"2026-05-05T22:15:05.278473Z","steps":["trace[849998101] 'agreement among raft nodes before linearized reading' (duration: 369.618448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:05.278541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:04.913051Z","time spent":"365.476038ms","remote":"127.0.0.1:35676","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-05T22:15:05.278541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:04.908564Z","time spent":"369.963326ms","remote":"127.0.0.1:35676","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-05T22:15:05.27854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.71891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T22:15:05.278635Z","caller":"traceutil/trace.go:171","msg":"trace[1869992655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"364.875284ms","start":"2026-05-05T22:15:04.913733Z","end":"2026-05-05T22:15:05.278608Z","steps":["trace[1869992655] 'agreement among raft nodes before linearized reading' (duration: 364.594398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:05.278692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:04.913721Z","time spent":"364.959226ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T22:15:19.770699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.984478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:15:19.770871Z","caller":"traceutil/trace.go:171","msg":"trace[1977183975] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"228.27547ms","start":"2026-05-05T22:15:19.542555Z","end":"2026-05-05T22:15:19.770831Z","steps":["trace[1977183975] 'read index received' (duration: 87.066296ms)","trace[1977183975] 'applied index is now lower than readState.Index' (duration: 141.207024ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:19.770994Z","caller":"traceutil/trace.go:171","msg":"trace[1203294567] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"246.169698ms","start":"2026-05-05T22:15:19.524799Z","end":"2026-05-05T22:15:19.770969Z","steps":["trace[1203294567] 'process raft request' (duration: 104.78949ms)","trace[1203294567] 'compare' (duration: 140.62765ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:15:19.771065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.494505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:19.771172Z","caller":"traceutil/trace.go:171","msg":"trace[1275360667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"228.634358ms","start":"2026-05-05T22:15:19.542513Z","end":"2026-05-05T22:15:19.771148Z","steps":["trace[1275360667] 'agreement among raft nodes before linearized reading' (duration: 228.485265ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:33.058489Z","caller":"traceutil/trace.go:171","msg":"trace[241383377] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"172.789605ms","start":"2026-05-05T22:16:32.885664Z","end":"2026-05-05T22:16:33.058453Z","steps":["trace[241383377] 'read index received' (duration: 172.613141ms)","trace[241383377] 'applied index is now lower than readState.Index' (duration: 175.024µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:33.05853Z","caller":"traceutil/trace.go:171","msg":"trace[212797615] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"189.376111ms","start":"2026-05-05T22:16:32.86912Z","end":"2026-05-05T22:16:33.058496Z","steps":["trace[212797615] 'process raft request' (duration: 189.108654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:33.058708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.01192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:33.058797Z","caller":"traceutil/trace.go:171","msg":"trace[2022006906] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1847; }","duration":"173.173404ms","start":"2026-05-05T22:16:32.885607Z","end":"2026-05-05T22:16:33.058781Z","steps":["trace[2022006906] 'agreement among raft nodes before linearized reading' (duration: 173.00189ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:33.073487Z","caller":"traceutil/trace.go:171","msg":"trace[1515411436] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"126.721445ms","start":"2026-05-05T22:16:32.946745Z","end":"2026-05-05T22:16:33.073467Z","steps":["trace[1515411436] 'process raft request' (duration: 126.428398ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:49.614965Z","caller":"traceutil/trace.go:171","msg":"trace[1660136045] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"173.721496ms","start":"2026-05-05T22:16:49.441209Z","end":"2026-05-05T22:16:49.61493Z","steps":["trace[1660136045] 'process raft request' (duration: 173.579403ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:49.616916Z","caller":"traceutil/trace.go:171","msg":"trace[681985088] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"138.141887ms","start":"2026-05-05T22:16:49.47875Z","end":"2026-05-05T22:16:49.616892Z","steps":["trace[681985088] 'process raft request' (duration: 137.86816ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:22.581698Z","caller":"traceutil/trace.go:171","msg":"trace[1767600658] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2249; }","duration":"120.660608ms","start":"2026-05-05T22:17:22.461005Z","end":"2026-05-05T22:17:22.581665Z","steps":["trace[1767600658] 'read index received' (duration: 120.511185ms)","trace[1767600658] 'applied index is now lower than readState.Index' (duration: 148.053µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:22.581768Z","caller":"traceutil/trace.go:171","msg":"trace[1384061949] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"137.309599ms","start":"2026-05-05T22:17:22.444428Z","end":"2026-05-05T22:17:22.581737Z","steps":["trace[1384061949] 'process raft request' (duration: 137.022092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:22.582083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.046237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T22:17:22.582187Z","caller":"traceutil/trace.go:171","msg":"trace[1021929870] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2168; }","duration":"121.16526ms","start":"2026-05-05T22:17:22.460966Z","end":"2026-05-05T22:17:22.582131Z","steps":["trace[1021929870] 'agreement among raft nodes before linearized reading' (duration: 120.836812ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:23.440623Z","caller":"traceutil/trace.go:171","msg":"trace[717987001] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"185.401468ms","start":"2026-05-05T22:17:23.255193Z","end":"2026-05-05T22:17:23.440595Z","steps":["trace[717987001] 'process raft request' (duration: 107.254355ms)","trace[717987001] 'compare' (duration: 77.945848ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:23.701982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.522951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T22:17:23.702064Z","caller":"traceutil/trace.go:171","msg":"trace[2020301018] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"112.664924ms","start":"2026-05-05T22:17:23.589379Z","end":"2026-05-05T22:17:23.702044Z","steps":["trace[2020301018] 'range keys from in-memory index tree' (duration: 112.309097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:23.702076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.132517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:23.702124Z","caller":"traceutil/trace.go:171","msg":"trace[1701408525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2172; }","duration":"157.178788ms","start":"2026-05-05T22:17:23.544932Z","end":"2026-05-05T22:17:23.702111Z","steps":["trace[1701408525] 'range keys from in-memory index tree' (duration: 157.037875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:29.980168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.229124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:29.98037Z","caller":"traceutil/trace.go:171","msg":"trace[1796278088] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"522.215125ms","start":"2026-05-05T22:17:29.458132Z","end":"2026-05-05T22:17:29.980348Z","steps":["trace[1796278088] 'process raft request' (duration: 180.701015ms)","trace[1796278088] 'compare' (duration: 340.819414ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:29.980424Z","caller":"traceutil/trace.go:171","msg":"trace[65587404] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"150.736294ms","start":"2026-05-05T22:17:29.829673Z","end":"2026-05-05T22:17:29.980409Z","steps":["trace[65587404] 'process raft request' (duration: 150.697333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:29.980478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:29.458104Z","time spent":"522.319989ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:29.980642Z","caller":"traceutil/trace.go:171","msg":"trace[723485020] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"167.575509ms","start":"2026-05-05T22:17:29.81305Z","end":"2026-05-05T22:17:29.980625Z","steps":["trace[723485020] 'process raft request' (duration: 167.236931ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:29.98073Z","caller":"traceutil/trace.go:171","msg":"trace[661194432] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2285; }","duration":"479.297341ms","start":"2026-05-05T22:17:29.501334Z","end":"2026-05-05T22:17:29.980631Z","steps":["trace[661194432] 'read index received' (duration: 137.432662ms)","trace[661194432] 'applied index is now lower than readState.Index' (duration: 341.858069ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:29.981001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.662879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T22:17:29.981082Z","caller":"traceutil/trace.go:171","msg":"trace[1977386616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2205; }","duration":"479.774332ms","start":"2026-05-05T22:17:29.501291Z","end":"2026-05-05T22:17:29.981066Z","steps":["trace[1977386616] 'agreement among raft nodes before linearized reading' (duration: 479.517156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:29.981084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.557322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:17:29.981132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:29.501275Z","time spent":"479.843274ms","remote":"127.0.0.1:35676","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-05T22:17:29.981145Z","caller":"traceutil/trace.go:171","msg":"trace[727219244] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2205; }","duration":"436.654614ms","start":"2026-05-05T22:17:29.544471Z","end":"2026-05-05T22:17:29.981126Z","steps":["trace[727219244] 'agreement among raft nodes before linearized reading' (duration: 436.52368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:29.981197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:29.544463Z","time spent":"436.720385ms","remote":"127.0.0.1:35830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:17:29.981236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.520575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T22:17:29.981338Z","caller":"traceutil/trace.go:171","msg":"trace[1542383289] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2205; }","duration":"230.667128ms","start":"2026-05-05T22:17:29.750653Z","end":"2026-05-05T22:17:29.98132Z","steps":["trace[1542383289] 'agreement among raft nodes before linearized reading' (duration: 230.483074ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:30.088516Z","caller":"traceutil/trace.go:171","msg":"trace[381746138] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"102.100922ms","start":"2026-05-05T22:17:29.98639Z","end":"2026-05-05T22:17:30.088491Z","steps":["trace[381746138] 'process raft request' (duration: 101.918627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:44.564424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.173673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:18:44.564587Z","caller":"traceutil/trace.go:171","msg":"trace[381519168] linearizableReadLoop","detail":"{readStateIndex:2912; appliedIndex:2909; }","duration":"279.697201ms","start":"2026-05-05T22:18:44.284876Z","end":"2026-05-05T22:18:44.564574Z","steps":["trace[381519168] 'read index received' (duration: 8.014116ms)","trace[381519168] 'applied index is now lower than readState.Index' (duration: 271.681985ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:44.564606Z","caller":"traceutil/trace.go:171","msg":"trace[1850334634] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"319.512556ms","start":"2026-05-05T22:18:44.245065Z","end":"2026-05-05T22:18:44.564577Z","steps":["trace[1850334634] 'process raft request' (duration: 188.115357ms)","trace[1850334634] 'compare' (duration: 130.983279ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:44.564704Z","caller":"traceutil/trace.go:171","msg":"trace[1080672107] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"315.97797ms","start":"2026-05-05T22:18:44.248716Z","end":"2026-05-05T22:18:44.564694Z","steps":["trace[1080672107] 'process raft request' (duration: 315.788795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:44.564751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:44.248689Z","time spent":"316.036442ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:18:44.564748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:44.245026Z","time spent":"319.648329ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:18:44.564837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.699551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:18:44.564892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.988938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:18:44.565008Z","caller":"traceutil/trace.go:171","msg":"trace[1385075570] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2804; }","duration":"280.124622ms","start":"2026-05-05T22:18:44.284855Z","end":"2026-05-05T22:18:44.56498Z","steps":["trace[1385075570] 'agreement among raft nodes before linearized reading' (duration: 279.880136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:44.565026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.947107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T22:18:44.564924Z","caller":"traceutil/trace.go:171","msg":"trace[353360109] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2804; }","duration":"199.803434ms","start":"2026-05-05T22:18:44.365099Z","end":"2026-05-05T22:18:44.564903Z","steps":["trace[353360109] 'agreement among raft nodes before linearized reading' (duration: 199.65627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:44.56509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.53362ms","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-05T22:18:44.565107Z","caller":"traceutil/trace.go:171","msg":"trace[268236028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2804; }","duration":"111.06582ms","start":"2026-05-05T22:18:44.454016Z","end":"2026-05-05T22:18:44.565082Z","steps":["trace[268236028] 'agreement among raft nodes before linearized reading' (duration: 110.830934ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:44.565126Z","caller":"traceutil/trace.go:171","msg":"trace[549817716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2804; }","duration":"133.617942ms","start":"2026-05-05T22:18:44.431497Z","end":"2026-05-05T22:18:44.565115Z","steps":["trace[549817716] 'agreement among raft nodes before linearized reading' (duration: 133.462798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:44.564939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.758822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T22:18:44.565168Z","caller":"traceutil/trace.go:171","msg":"trace[367789865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2804; }","duration":"133.009628ms","start":"2026-05-05T22:18:44.432146Z","end":"2026-05-05T22:18:44.565156Z","steps":["trace[367789865] 'agreement among raft nodes before linearized reading' (duration: 132.714121ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:21:10.233742Z","caller":"traceutil/trace.go:171","msg":"trace[777914845] transaction","detail":"{read_only:false; response_revision:3774; number_of_response:1; }","duration":"112.206986ms","start":"2026-05-05T22:21:10.121426Z","end":"2026-05-05T22:21:10.233633Z","steps":["trace[777914845] 'process raft request' (duration: 111.947699ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:24.898113Z","caller":"traceutil/trace.go:171","msg":"trace[19824844] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"113.588651ms","start":"2026-05-05T22:22:24.784508Z","end":"2026-05-05T22:22:24.898097Z","steps":["trace[19824844] 'process raft request' (duration: 113.299554ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:24.901848Z","caller":"traceutil/trace.go:171","msg":"trace[1755119079] linearizableReadLoop","detail":"{readStateIndex:4833; appliedIndex:4831; }","duration":"113.623841ms","start":"2026-05-05T22:22:24.788209Z","end":"2026-05-05T22:22:24.901833Z","steps":["trace[1755119079] 'read index received' (duration: 109.647156ms)","trace[1755119079] 'applied index is now lower than readState.Index' (duration: 3.975895ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:24.901941Z","caller":"traceutil/trace.go:171","msg":"trace[110578402] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"108.699113ms","start":"2026-05-05T22:22:24.793234Z","end":"2026-05-05T22:22:24.901933Z","steps":["trace[110578402] 'process raft request' (duration: 108.54752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:24.902087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.276682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-05T22:22:24.902108Z","caller":"traceutil/trace.go:171","msg":"trace[790865499] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"117.554497ms","start":"2026-05-05T22:22:24.784528Z","end":"2026-05-05T22:22:24.902082Z","steps":["trace[790865499] 'process raft request' (duration: 117.135847ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:24.902168Z","caller":"traceutil/trace.go:171","msg":"trace[565498324] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4654; }","duration":"113.388745ms","start":"2026-05-05T22:22:24.788758Z","end":"2026-05-05T22:22:24.902147Z","steps":["trace[565498324] 'agreement among raft nodes before linearized reading' (duration: 113.193121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:24.902395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.645879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:61922"} {"level":"warn","ts":"2026-05-05T22:22:24.902434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.161224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:24.902475Z","caller":"traceutil/trace.go:171","msg":"trace[470083499] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4654; }","duration":"110.749432ms","start":"2026-05-05T22:22:24.791709Z","end":"2026-05-05T22:22:24.902458Z","steps":["trace[470083499] 'agreement among raft nodes before linearized reading' (duration: 110.472915ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:24.902513Z","caller":"traceutil/trace.go:171","msg":"trace[148116045] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4654; }","duration":"114.334928ms","start":"2026-05-05T22:22:24.788161Z","end":"2026-05-05T22:22:24.902496Z","steps":["trace[148116045] 'agreement among raft nodes before linearized reading' (duration: 114.142633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:32.668291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.75461ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:32.668441Z","caller":"traceutil/trace.go:171","msg":"trace[235384197] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4926; }","duration":"165.930004ms","start":"2026-05-05T22:22:32.502492Z","end":"2026-05-05T22:22:32.668422Z","steps":["trace[235384197] 'range keys from in-memory index tree' (duration: 165.73182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:32.668535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.906953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:22:32.668641Z","caller":"traceutil/trace.go:171","msg":"trace[1949583450] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5114; }","duration":"124.283499ms","start":"2026-05-05T22:22:32.544341Z","end":"2026-05-05T22:22:32.668625Z","steps":["trace[1949583450] 'read index received' (duration: 60.011µs)","trace[1949583450] 'applied index is now lower than readState.Index' (duration: 124.222468ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:32.668655Z","caller":"traceutil/trace.go:171","msg":"trace[1899697758] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"165.926013ms","start":"2026-05-05T22:22:32.50271Z","end":"2026-05-05T22:22:32.668636Z","steps":["trace[1899697758] 'process raft request' (duration: 33.827326ms)","trace[1899697758] 'compare' (duration: 131.765749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:22:32.668702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.377582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:32.668729Z","caller":"traceutil/trace.go:171","msg":"trace[218951963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4927; }","duration":"124.448103ms","start":"2026-05-05T22:22:32.544272Z","end":"2026-05-05T22:22:32.66872Z","steps":["trace[218951963] 'agreement among raft nodes before linearized reading' (duration: 124.400632ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:35.124139Z","caller":"traceutil/trace.go:171","msg":"trace[985775062] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"174.855098ms","start":"2026-05-05T22:22:34.949261Z","end":"2026-05-05T22:22:35.124116Z","steps":["trace[985775062] 'process raft request' (duration: 174.713525ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:35.216561Z","caller":"traceutil/trace.go:171","msg":"trace[1575160299] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"140.582432ms","start":"2026-05-05T22:22:35.075945Z","end":"2026-05-05T22:22:35.216528Z","steps":["trace[1575160299] 'process raft request' (duration: 139.637819ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:44.226013Z","caller":"traceutil/trace.go:171","msg":"trace[1483784893] linearizableReadLoop","detail":"{readStateIndex:5360; appliedIndex:5359; }","duration":"223.258045ms","start":"2026-05-05T22:22:44.002737Z","end":"2026-05-05T22:22:44.225995Z","steps":["trace[1483784893] 'read index received' (duration: 223.084241ms)","trace[1483784893] 'applied index is now lower than readState.Index' (duration: 173.244µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:44.22629Z","caller":"traceutil/trace.go:171","msg":"trace[1455741190] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"226.344469ms","start":"2026-05-05T22:22:43.999936Z","end":"2026-05-05T22:22:44.226281Z","steps":["trace[1455741190] 'process raft request' (duration: 225.936469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:44.226654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.661375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" ","response":"range_response_count:12 size:4407"} {"level":"info","ts":"2026-05-05T22:22:44.22672Z","caller":"traceutil/trace.go:171","msg":"trace[2139294412] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:12; response_revision:5164; }","duration":"223.751118ms","start":"2026-05-05T22:22:44.002954Z","end":"2026-05-05T22:22:44.226705Z","steps":["trace[2139294412] 'agreement among raft nodes before linearized reading' (duration: 223.416189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:44.226725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.975673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17455"} {"level":"info","ts":"2026-05-05T22:22:44.226746Z","caller":"traceutil/trace.go:171","msg":"trace[1855054665] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5164; }","duration":"224.033424ms","start":"2026-05-05T22:22:44.002708Z","end":"2026-05-05T22:22:44.226741Z","steps":["trace[1855054665] 'agreement among raft nodes before linearized reading' (duration: 223.939322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:44.227096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.689292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" ","response":"range_response_count:43 size:291733"} {"level":"info","ts":"2026-05-05T22:22:44.227151Z","caller":"traceutil/trace.go:171","msg":"trace[1342140201] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:43; response_revision:5164; }","duration":"222.756565ms","start":"2026-05-05T22:22:44.004382Z","end":"2026-05-05T22:22:44.227138Z","steps":["trace[1342140201] 'agreement among raft nodes before linearized reading' (duration: 222.319884ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:51.512158Z","caller":"traceutil/trace.go:171","msg":"trace[124167525] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"124.139454ms","start":"2026-05-05T22:22:51.387971Z","end":"2026-05-05T22:22:51.512111Z","steps":["trace[124167525] 'process raft request' (duration: 101.57826ms)","trace[124167525] 'compare' (duration: 22.266817ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:51.582846Z","caller":"traceutil/trace.go:171","msg":"trace[1259201906] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5520; }","duration":"186.245742ms","start":"2026-05-05T22:22:51.396543Z","end":"2026-05-05T22:22:51.582789Z","steps":["trace[1259201906] 'read index received' (duration: 93.033584ms)","trace[1259201906] 'applied index is now lower than readState.Index' (duration: 93.211028ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:51.582993Z","caller":"traceutil/trace.go:171","msg":"trace[1209704128] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"194.061122ms","start":"2026-05-05T22:22:51.388904Z","end":"2026-05-05T22:22:51.582965Z","steps":["trace[1209704128] 'process raft request' (duration: 193.695603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:51.583237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.675034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f0d5bfc6-5100-48dc-a24b-b37920ee8e0d\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T22:22:51.583383Z","caller":"traceutil/trace.go:171","msg":"trace[1001254808] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f0d5bfc6-5100-48dc-a24b-b37920ee8e0d; range_end:; response_count:1; response_revision:5322; }","duration":"186.834377ms","start":"2026-05-05T22:22:51.396491Z","end":"2026-05-05T22:22:51.583325Z","steps":["trace[1001254808] 'agreement among raft nodes before linearized reading' (duration: 186.457728ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:24:28.752942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3047} {"level":"info","ts":"2026-05-05T22:24:28.836476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3047,"took":"82.059302ms","hash":4220765373,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":32133120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T22:24:28.83662Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4220765373,"revision":3047,"compact-revision":-1} {"level":"info","ts":"2026-05-05T22:28:53.669502Z","caller":"traceutil/trace.go:171","msg":"trace[457056410] transaction","detail":"{read_only:false; response_revision:8135; number_of_response:1; }","duration":"140.468302ms","start":"2026-05-05T22:28:53.529005Z","end":"2026-05-05T22:28:53.669474Z","steps":["trace[457056410] 'process raft request' (duration: 140.294848ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:28:53.699674Z","caller":"traceutil/trace.go:171","msg":"trace[1175664929] linearizableReadLoop","detail":"{readStateIndex:8422; appliedIndex:8420; }","duration":"155.543013ms","start":"2026-05-05T22:28:53.544105Z","end":"2026-05-05T22:28:53.699648Z","steps":["trace[1175664929] 'read index received' (duration: 125.238025ms)","trace[1175664929] 'applied index is now lower than readState.Index' (duration: 30.303868ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:28:53.699792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.665886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:28:53.699759Z","caller":"traceutil/trace.go:171","msg":"trace[1521335564] transaction","detail":"{read_only:false; response_revision:8136; number_of_response:1; }","duration":"168.69388ms","start":"2026-05-05T22:28:53.531021Z","end":"2026-05-05T22:28:53.699714Z","steps":["trace[1521335564] 'process raft request' (duration: 168.492315ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:28:53.699826Z","caller":"traceutil/trace.go:171","msg":"trace[1178418589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8136; }","duration":"155.744158ms","start":"2026-05-05T22:28:53.544072Z","end":"2026-05-05T22:28:53.699816Z","steps":["trace[1178418589] 'agreement among raft nodes before linearized reading' (duration: 155.672547ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:04.946181Z","caller":"traceutil/trace.go:171","msg":"trace[1118334160] transaction","detail":"{read_only:false; response_revision:8194; number_of_response:1; }","duration":"148.287159ms","start":"2026-05-05T22:29:04.797867Z","end":"2026-05-05T22:29:04.946155Z","steps":["trace[1118334160] 'process raft request' (duration: 54.575614ms)","trace[1118334160] 'compare' (duration: 93.51071ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:29:04.94633Z","caller":"traceutil/trace.go:171","msg":"trace[1022290033] transaction","detail":"{read_only:false; response_revision:8195; number_of_response:1; }","duration":"115.132921ms","start":"2026-05-05T22:29:04.831157Z","end":"2026-05-05T22:29:04.94629Z","steps":["trace[1022290033] 'process raft request' (duration: 114.936106ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:05.705769Z","caller":"traceutil/trace.go:171","msg":"trace[1028537479] linearizableReadLoop","detail":"{readStateIndex:8485; appliedIndex:8484; }","duration":"161.350714ms","start":"2026-05-05T22:29:05.544392Z","end":"2026-05-05T22:29:05.705743Z","steps":["trace[1028537479] 'read index received' (duration: 161.165249ms)","trace[1028537479] 'applied index is now lower than readState.Index' (duration: 184.735µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:29:05.705886Z","caller":"traceutil/trace.go:171","msg":"trace[1236783087] transaction","detail":"{read_only:false; response_revision:8196; number_of_response:1; }","duration":"180.508834ms","start":"2026-05-05T22:29:05.525334Z","end":"2026-05-05T22:29:05.705843Z","steps":["trace[1236783087] 'process raft request' (duration: 180.272058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:29:05.70593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.549497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:29:05.706094Z","caller":"traceutil/trace.go:171","msg":"trace[996023849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8196; }","duration":"161.774334ms","start":"2026-05-05T22:29:05.544305Z","end":"2026-05-05T22:29:05.706079Z","steps":["trace[996023849] 'agreement among raft nodes before linearized reading' (duration: 161.548258ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:05.900871Z","caller":"traceutil/trace.go:171","msg":"trace[114250702] linearizableReadLoop","detail":"{readStateIndex:8486; appliedIndex:8485; }","duration":"156.414785ms","start":"2026-05-05T22:29:05.744428Z","end":"2026-05-05T22:29:05.900842Z","steps":["trace[114250702] 'read index received' (duration: 126.543316ms)","trace[114250702] 'applied index is now lower than readState.Index' (duration: 29.870599ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:29:05.901088Z","caller":"traceutil/trace.go:171","msg":"trace[940746192] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"177.611365ms","start":"2026-05-05T22:29:05.723466Z","end":"2026-05-05T22:29:05.901078Z","steps":["trace[940746192] 'process raft request' (duration: 147.48578ms)","trace[940746192] 'compare' (duration: 29.729796ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:29:05.901317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.902937ms","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-05T22:29:05.901348Z","caller":"traceutil/trace.go:171","msg":"trace[527914291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8197; }","duration":"156.982299ms","start":"2026-05-05T22:29:05.744357Z","end":"2026-05-05T22:29:05.901339Z","steps":["trace[527914291] 'agreement among raft nodes before linearized reading' (duration: 156.854316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:29:06.450724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.675291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k72kd\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T22:29:06.450815Z","caller":"traceutil/trace.go:171","msg":"trace[775477389] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k72kd; range_end:; response_count:1; response_revision:8202; }","duration":"131.809885ms","start":"2026-05-05T22:29:06.318983Z","end":"2026-05-05T22:29:06.450793Z","steps":["trace[775477389] 'range keys from in-memory index tree' (duration: 131.386905ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:28.763421Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6205} {"level":"info","ts":"2026-05-05T22:29:28.85734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6205,"took":"92.475711ms","hash":3644931331,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":22990848,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T22:29:28.857449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3644931331,"revision":6205,"compact-revision":3047} {"level":"info","ts":"2026-05-05T22:29:37.277897Z","caller":"traceutil/trace.go:171","msg":"trace[598580638] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"184.721254ms","start":"2026-05-05T22:29:37.093158Z","end":"2026-05-05T22:29:37.277879Z","steps":["trace[598580638] 'process raft request' (duration: 184.602002ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:37.340705Z","caller":"traceutil/trace.go:171","msg":"trace[466013414] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"195.156866ms","start":"2026-05-05T22:29:37.145519Z","end":"2026-05-05T22:29:37.340676Z","steps":["trace[466013414] 'process raft request' (duration: 194.968991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:46.220405Z","caller":"traceutil/trace.go:171","msg":"trace[1597397740] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"106.033006ms","start":"2026-05-05T22:29:46.114316Z","end":"2026-05-05T22:29:46.220349Z","steps":["trace[1597397740] 'process raft request' (duration: 105.761819ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:47.185486Z","caller":"traceutil/trace.go:171","msg":"trace[999185327] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"155.670268ms","start":"2026-05-05T22:29:47.029778Z","end":"2026-05-05T22:29:47.185449Z","steps":["trace[999185327] 'process raft request' (duration: 155.449463ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:47.371462Z","caller":"traceutil/trace.go:171","msg":"trace[601666049] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"281.953659ms","start":"2026-05-05T22:29:47.089467Z","end":"2026-05-05T22:29:47.371421Z","steps":["trace[601666049] 'process raft request' (duration: 196.848616ms)","trace[601666049] 'compare' (duration: 84.655722ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:29:47.41427Z","caller":"traceutil/trace.go:171","msg":"trace[1322070979] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"105.731459ms","start":"2026-05-05T22:29:47.308516Z","end":"2026-05-05T22:29:47.414247Z","steps":["trace[1322070979] 'process raft request' (duration: 105.574775ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:47.414248Z","caller":"traceutil/trace.go:171","msg":"trace[191596540] linearizableReadLoop","detail":"{readStateIndex:8824; appliedIndex:8822; }","duration":"221.17296ms","start":"2026-05-05T22:29:47.193033Z","end":"2026-05-05T22:29:47.414206Z","steps":["trace[191596540] 'read index received' (duration: 93.295769ms)","trace[191596540] 'applied index is now lower than readState.Index' (duration: 127.871521ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:29:47.41448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.437947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-05-05T22:29:47.414548Z","caller":"traceutil/trace.go:171","msg":"trace[1413973646] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8522; }","duration":"221.55617ms","start":"2026-05-05T22:29:47.192976Z","end":"2026-05-05T22:29:47.414532Z","steps":["trace[1413973646] 'agreement among raft nodes before linearized reading' (duration: 221.336174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:29:47.414582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.201831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:29:47.414681Z","caller":"traceutil/trace.go:171","msg":"trace[695932448] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:8522; }","duration":"141.311304ms","start":"2026-05-05T22:29:47.27335Z","end":"2026-05-05T22:29:47.414661Z","steps":["trace[695932448] 'agreement among raft nodes before linearized reading' (duration: 141.137869ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:47.889879Z","caller":"traceutil/trace.go:171","msg":"trace[1361905453] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"110.975035ms","start":"2026-05-05T22:29:47.778884Z","end":"2026-05-05T22:29:47.889859Z","steps":["trace[1361905453] 'process raft request' (duration: 110.84254ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:48.440701Z","caller":"traceutil/trace.go:171","msg":"trace[30178917] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"130.75628ms","start":"2026-05-05T22:29:48.309922Z","end":"2026-05-05T22:29:48.440678Z","steps":["trace[30178917] 'process raft request' (duration: 130.565165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:29:48.441007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.244109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T22:29:48.441082Z","caller":"traceutil/trace.go:171","msg":"trace[457009601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8525; }","duration":"113.381232ms","start":"2026-05-05T22:29:48.327685Z","end":"2026-05-05T22:29:48.441066Z","steps":["trace[457009601] 'agreement among raft nodes before linearized reading' (duration: 113.077175ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:48.440698Z","caller":"traceutil/trace.go:171","msg":"trace[441165871] linearizableReadLoop","detail":"{readStateIndex:8829; appliedIndex:8828; }","duration":"112.914371ms","start":"2026-05-05T22:29:48.327749Z","end":"2026-05-05T22:29:48.440663Z","steps":["trace[441165871] 'read index received' (duration: 112.895001ms)","trace[441165871] 'applied index is now lower than readState.Index' (duration: 16.82µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:29:48.533458Z","caller":"traceutil/trace.go:171","msg":"trace[112652747] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"191.319093ms","start":"2026-05-05T22:29:48.342104Z","end":"2026-05-05T22:29:48.533423Z","steps":["trace[112652747] 'process raft request' (duration: 123.764321ms)","trace[112652747] 'compare' (duration: 67.338607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:29:48.533756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.722286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-05T22:29:48.533788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.086975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T22:29:48.53384Z","caller":"traceutil/trace.go:171","msg":"trace[196036808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8527; }","duration":"177.848959ms","start":"2026-05-05T22:29:48.355972Z","end":"2026-05-05T22:29:48.533821Z","steps":["trace[196036808] 'agreement among raft nodes before linearized reading' (duration: 177.583243ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:29:48.533846Z","caller":"traceutil/trace.go:171","msg":"trace[1848124676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8527; }","duration":"203.207849ms","start":"2026-05-05T22:29:48.330622Z","end":"2026-05-05T22:29:48.53383Z","steps":["trace[1848124676] 'agreement among raft nodes before linearized reading' (duration: 203.009114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:29:48.533801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.697828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T22:29:48.534039Z","caller":"traceutil/trace.go:171","msg":"trace[906702355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8527; }","duration":"206.010205ms","start":"2026-05-05T22:29:48.328009Z","end":"2026-05-05T22:29:48.534019Z","steps":["trace[906702355] 'agreement among raft nodes before linearized reading' (duration: 205.502213ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:30:37.385892Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002465.wal"} {"level":"info","ts":"2026-05-05T22:31:01.792403Z","caller":"traceutil/trace.go:171","msg":"trace[344920550] transaction","detail":"{read_only:false; response_revision:9259; number_of_response:1; }","duration":"133.058994ms","start":"2026-05-05T22:31:01.659313Z","end":"2026-05-05T22:31:01.792372Z","steps":["trace[344920550] 'process raft request' (duration: 91.577388ms)","trace[344920550] 'compare' (duration: 41.24522ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:31:57.189917Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T22:31:57.195871Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T22:31:57.195951Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T22:34:28.770484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8332} {"level":"info","ts":"2026-05-05T22:34:28.85674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8332,"took":"84.248671ms","hash":1133345574,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":26525696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T22:34:28.856873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1133345574,"revision":8332,"compact-revision":6205} {"level":"warn","ts":"2026-05-05T22:34:40.288416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.583532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-05T22:34:40.288468Z","caller":"traceutil/trace.go:171","msg":"trace[622348216] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11075; }","duration":"115.685974ms","start":"2026-05-05T22:34:40.172771Z","end":"2026-05-05T22:34:40.288457Z","steps":["trace[622348216] 'range keys from in-memory index tree' (duration: 115.450968ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:34:41.942778Z","caller":"traceutil/trace.go:171","msg":"trace[1816206290] linearizableReadLoop","detail":"{readStateIndex:11460; appliedIndex:11459; }","duration":"207.483195ms","start":"2026-05-05T22:34:41.735276Z","end":"2026-05-05T22:34:41.942759Z","steps":["trace[1816206290] 'read index received' (duration: 207.25697ms)","trace[1816206290] 'applied index is now lower than readState.Index' (duration: 225.255µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:34:41.942818Z","caller":"traceutil/trace.go:171","msg":"trace[1853615326] transaction","detail":"{read_only:false; response_revision:11081; number_of_response:1; }","duration":"207.613718ms","start":"2026-05-05T22:34:41.735179Z","end":"2026-05-05T22:34:41.942793Z","steps":["trace[1853615326] 'process raft request' (duration: 207.420594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:34:41.942997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.69958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T22:34:41.943073Z","caller":"traceutil/trace.go:171","msg":"trace[739035063] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:11081; }","duration":"207.819733ms","start":"2026-05-05T22:34:41.735236Z","end":"2026-05-05T22:34:41.943056Z","steps":["trace[739035063] 'agreement among raft nodes before linearized reading' (duration: 207.599168ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:39:28.778078Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11012} {"level":"info","ts":"2026-05-05T22:39:28.855741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11012,"took":"74.866917ms","hash":2381735463,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":28229632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T22:39:28.855797Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2381735463,"revision":11012,"compact-revision":8332} {"level":"info","ts":"2026-05-05T22:41:23.413032Z","caller":"traceutil/trace.go:171","msg":"trace[1253917571] transaction","detail":"{read_only:false; response_revision:13877; number_of_response:1; }","duration":"102.589303ms","start":"2026-05-05T22:41:23.310411Z","end":"2026-05-05T22:41:23.413001Z","steps":["trace[1253917571] 'process raft request' (duration: 102.394949ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:44:28.784732Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13045} {"level":"info","ts":"2026-05-05T22:44:28.862237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13045,"took":"74.666103ms","hash":24175468,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":31059968,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T22:44:28.862311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":24175468,"revision":13045,"compact-revision":11012} {"level":"info","ts":"2026-05-05T22:45:50.852658Z","caller":"traceutil/trace.go:171","msg":"trace[640865370] transaction","detail":"{read_only:false; response_revision:16332; number_of_response:1; }","duration":"102.51406ms","start":"2026-05-05T22:45:50.750116Z","end":"2026-05-05T22:45:50.85263Z","steps":["trace[640865370] 'process raft request' (duration: 102.362977ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:48:46.322335Z","caller":"traceutil/trace.go:171","msg":"trace[55494627] linearizableReadLoop","detail":"{readStateIndex:18188; appliedIndex:18187; }","duration":"320.835749ms","start":"2026-05-05T22:48:46.001477Z","end":"2026-05-05T22:48:46.322312Z","steps":["trace[55494627] 'read index received' (duration: 256.483627ms)","trace[55494627] 'applied index is now lower than readState.Index' (duration: 64.351012ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:48:46.322485Z","caller":"traceutil/trace.go:171","msg":"trace[1932294025] transaction","detail":"{read_only:false; response_revision:17596; number_of_response:1; }","duration":"374.195288ms","start":"2026-05-05T22:48:45.948275Z","end":"2026-05-05T22:48:46.32247Z","steps":["trace[1932294025] 'process raft request' (duration: 309.699373ms)","trace[1932294025] 'compare' (duration: 64.242919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:48:46.322664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.139101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-05T22:48:46.322741Z","caller":"traceutil/trace.go:171","msg":"trace[630484621] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17596; }","duration":"229.259664ms","start":"2026-05-05T22:48:46.093463Z","end":"2026-05-05T22:48:46.322722Z","steps":["trace[630484621] 'agreement among raft nodes before linearized reading' (duration: 229.060149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:48:46.322737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:48:45.948262Z","time spent":"374.254519ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:48:46.32302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.536045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T22:48:46.323082Z","caller":"traceutil/trace.go:171","msg":"trace[2124331402] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:17596; }","duration":"321.649008ms","start":"2026-05-05T22:48:46.001414Z","end":"2026-05-05T22:48:46.323063Z","steps":["trace[2124331402] 'agreement among raft nodes before linearized reading' (duration: 321.555046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:48:46.323129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:48:46.001388Z","time spent":"321.72528ms","remote":"127.0.0.1:53708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:48:46.641348Z","caller":"traceutil/trace.go:171","msg":"trace[529204868] transaction","detail":"{read_only:false; response_revision:17598; number_of_response:1; }","duration":"311.679501ms","start":"2026-05-05T22:48:46.329646Z","end":"2026-05-05T22:48:46.641326Z","steps":["trace[529204868] 'process raft request' (duration: 311.381394ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:48:46.641389Z","caller":"traceutil/trace.go:171","msg":"trace[1692058968] linearizableReadLoop","detail":"{readStateIndex:18191; appliedIndex:18189; }","duration":"273.862843ms","start":"2026-05-05T22:48:46.36751Z","end":"2026-05-05T22:48:46.641373Z","steps":["trace[1692058968] 'read index received' (duration: 273.683149ms)","trace[1692058968] 'applied index is now lower than readState.Index' (duration: 179.024µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:48:46.641476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:48:46.329633Z","time spent":"311.777103ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:48:46.641543Z","caller":"traceutil/trace.go:171","msg":"trace[442966739] transaction","detail":"{read_only:false; response_revision:17599; number_of_response:1; }","duration":"289.038167ms","start":"2026-05-05T22:48:46.352496Z","end":"2026-05-05T22:48:46.641534Z","steps":["trace[442966739] 'process raft request' (duration: 288.73289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:48:46.641818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.107994ms","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-05T22:48:46.641862Z","caller":"traceutil/trace.go:171","msg":"trace[2125569176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17599; }","duration":"313.168155ms","start":"2026-05-05T22:48:46.328682Z","end":"2026-05-05T22:48:46.64185Z","steps":["trace[2125569176] 'agreement among raft nodes before linearized reading' (duration: 313.024472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:48:46.641898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:48:46.328664Z","time spent":"313.226897ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T22:48:46.641912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.118639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-z7sr8\" ","response":"range_response_count:1 size:20850"} {"level":"info","ts":"2026-05-05T22:48:46.641996Z","caller":"traceutil/trace.go:171","msg":"trace[688645318] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-z7sr8; range_end:; response_count:1; response_revision:17600; }","duration":"274.236392ms","start":"2026-05-05T22:48:46.36774Z","end":"2026-05-05T22:48:46.641976Z","steps":["trace[688645318] 'agreement among raft nodes before linearized reading' (duration: 274.015577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:48:46.642254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.665059ms","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-05T22:48:46.642289Z","caller":"traceutil/trace.go:171","msg":"trace[1506458569] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17600; }","duration":"215.7404ms","start":"2026-05-05T22:48:46.42654Z","end":"2026-05-05T22:48:46.642281Z","steps":["trace[1506458569] 'agreement among raft nodes before linearized reading' (duration: 215.685909ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:48:46.90478Z","caller":"traceutil/trace.go:171","msg":"trace[313394271] linearizableReadLoop","detail":"{readStateIndex:18193; appliedIndex:18192; }","duration":"256.430586ms","start":"2026-05-05T22:48:46.64832Z","end":"2026-05-05T22:48:46.90475Z","steps":["trace[313394271] 'read index received' (duration: 226.089409ms)","trace[313394271] 'applied index is now lower than readState.Index' (duration: 30.339827ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:48:46.905073Z","caller":"traceutil/trace.go:171","msg":"trace[282335494] transaction","detail":"{read_only:false; response_revision:17601; number_of_response:1; }","duration":"258.185747ms","start":"2026-05-05T22:48:46.646873Z","end":"2026-05-05T22:48:46.905059Z","steps":["trace[282335494] 'process raft request' (duration: 227.506012ms)","trace[282335494] 'compare' (duration: 30.250375ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:48:46.90531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.966838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-05T22:48:46.9054Z","caller":"traceutil/trace.go:171","msg":"trace[469902503] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17601; }","duration":"257.120183ms","start":"2026-05-05T22:48:46.648257Z","end":"2026-05-05T22:48:46.905378Z","steps":["trace[469902503] 'agreement among raft nodes before linearized reading' (duration: 256.808555ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:48:46.990004Z","caller":"traceutil/trace.go:171","msg":"trace[21798771] transaction","detail":"{read_only:false; response_revision:17602; number_of_response:1; }","duration":"329.21997ms","start":"2026-05-05T22:48:46.660756Z","end":"2026-05-05T22:48:46.989976Z","steps":["trace[21798771] 'process raft request' (duration: 328.849821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:48:46.990184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:48:46.660736Z","time spent":"329.347822ms","remote":"127.0.0.1:35602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20967,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:48:46.990975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.419054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T22:48:46.991101Z","caller":"traceutil/trace.go:171","msg":"trace[1192169319] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:17602; }","duration":"198.628659ms","start":"2026-05-05T22:48:46.792448Z","end":"2026-05-05T22:48:46.991077Z","steps":["trace[1192169319] 'agreement among raft nodes before linearized reading' (duration: 198.335622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:48:46.991021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.643504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-05T22:48:46.991153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.540798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T22:48:46.991223Z","caller":"traceutil/trace.go:171","msg":"trace[658152179] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:17602; }","duration":"199.879201ms","start":"2026-05-05T22:48:46.791313Z","end":"2026-05-05T22:48:46.991192Z","steps":["trace[658152179] 'agreement among raft nodes before linearized reading' (duration: 199.506731ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:48:46.991245Z","caller":"traceutil/trace.go:171","msg":"trace[1792803711] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:17602; }","duration":"173.719913ms","start":"2026-05-05T22:48:46.817501Z","end":"2026-05-05T22:48:46.991221Z","steps":["trace[1792803711] 'agreement among raft nodes before linearized reading' (duration: 173.256911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:48:47.042416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.355855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18824"} {"level":"info","ts":"2026-05-05T22:48:47.042516Z","caller":"traceutil/trace.go:171","msg":"trace[36902709] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:17603; }","duration":"135.514469ms","start":"2026-05-05T22:48:46.906978Z","end":"2026-05-05T22:48:47.042493Z","steps":["trace[36902709] 'agreement among raft nodes before linearized reading' (duration: 135.199772ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:49:28.79072Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15545} {"level":"info","ts":"2026-05-05T22:49:28.883761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15545,"took":"89.793882ms","hash":1770506060,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":33677312,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T22:49:28.883831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1770506060,"revision":15545,"compact-revision":13045} {"level":"info","ts":"2026-05-05T22:52:26.205665Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T22:52:26.211979Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-05T22:52:26.212158Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-05T22:54:04.545112Z","caller":"traceutil/trace.go:171","msg":"trace[1352281925] transaction","detail":"{read_only:false; response_revision:20043; number_of_response:1; }","duration":"111.643986ms","start":"2026-05-05T22:54:04.433442Z","end":"2026-05-05T22:54:04.545086Z","steps":["trace[1352281925] 'process raft request' (duration: 111.458501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:09.772381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.217749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T22:54:09.772466Z","caller":"traceutil/trace.go:171","msg":"trace[557714168] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:20066; }","duration":"107.340532ms","start":"2026-05-05T22:54:09.665107Z","end":"2026-05-05T22:54:09.772447Z","steps":["trace[557714168] 'count revisions from in-memory index tree' (duration: 107.158988ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:54:09.924304Z","caller":"traceutil/trace.go:171","msg":"trace[583428763] transaction","detail":"{read_only:false; response_revision:20067; number_of_response:1; }","duration":"126.466929ms","start":"2026-05-05T22:54:09.797817Z","end":"2026-05-05T22:54:09.924284Z","steps":["trace[583428763] 'process raft request' (duration: 126.118671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:10.228291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.174435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T22:54:10.228404Z","caller":"traceutil/trace.go:171","msg":"trace[417388983] linearizableReadLoop","detail":"{readStateIndex:20744; appliedIndex:20743; }","duration":"225.75494ms","start":"2026-05-05T22:54:10.002633Z","end":"2026-05-05T22:54:10.228387Z","steps":["trace[417388983] 'read index received' (duration: 114.422942ms)","trace[417388983] 'applied index is now lower than readState.Index' (duration: 111.330598ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:54:10.228575Z","caller":"traceutil/trace.go:171","msg":"trace[1679123088] transaction","detail":"{read_only:false; response_revision:20069; number_of_response:1; }","duration":"299.086161ms","start":"2026-05-05T22:54:09.92941Z","end":"2026-05-05T22:54:10.228531Z","steps":["trace[1679123088] 'process raft request' (duration: 187.587019ms)","trace[1679123088] 'compare' (duration: 110.886296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:54:10.228762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.075768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-05T22:54:10.228873Z","caller":"traceutil/trace.go:171","msg":"trace[683397664] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:20069; }","duration":"226.259722ms","start":"2026-05-05T22:54:10.002585Z","end":"2026-05-05T22:54:10.228845Z","steps":["trace[683397664] 'agreement among raft nodes before linearized reading' (duration: 225.969985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:10.248322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.972188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-05T22:54:10.248383Z","caller":"traceutil/trace.go:171","msg":"trace[1969014424] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:20070; }","duration":"196.05164ms","start":"2026-05-05T22:54:10.052319Z","end":"2026-05-05T22:54:10.24837Z","steps":["trace[1969014424] 'agreement among raft nodes before linearized reading' (duration: 195.821435ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:54:10.514554Z","caller":"traceutil/trace.go:171","msg":"trace[1965444835] linearizableReadLoop","detail":"{readStateIndex:20746; appliedIndex:20745; }","duration":"262.887786ms","start":"2026-05-05T22:54:10.251638Z","end":"2026-05-05T22:54:10.514526Z","steps":["trace[1965444835] 'read index received' (duration: 262.591469ms)","trace[1965444835] 'applied index is now lower than readState.Index' (duration: 295.367µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:54:10.514861Z","caller":"traceutil/trace.go:171","msg":"trace[1596920540] transaction","detail":"{read_only:false; response_revision:20071; number_of_response:1; }","duration":"281.835119ms","start":"2026-05-05T22:54:10.233012Z","end":"2026-05-05T22:54:10.514847Z","steps":["trace[1596920540] 'process raft request' (duration: 281.368028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:10.515153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.49717ms","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-05T22:54:10.515195Z","caller":"traceutil/trace.go:171","msg":"trace[1897361002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20071; }","duration":"263.581783ms","start":"2026-05-05T22:54:10.251603Z","end":"2026-05-05T22:54:10.515185Z","steps":["trace[1897361002] 'agreement among raft nodes before linearized reading' (duration: 263.434419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:10.51535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.342334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T22:54:10.515389Z","caller":"traceutil/trace.go:171","msg":"trace[1469172553] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:20071; }","duration":"159.408676ms","start":"2026-05-05T22:54:10.355969Z","end":"2026-05-05T22:54:10.515378Z","steps":["trace[1469172553] 'agreement among raft nodes before linearized reading' (duration: 159.347165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:10.712293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.084963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T22:54:10.712402Z","caller":"traceutil/trace.go:171","msg":"trace[1682332746] linearizableReadLoop","detail":"{readStateIndex:20747; appliedIndex:20746; }","duration":"145.754659ms","start":"2026-05-05T22:54:10.56663Z","end":"2026-05-05T22:54:10.712385Z","steps":["trace[1682332746] 'read index received' (duration: 26.489093ms)","trace[1682332746] 'applied index is now lower than readState.Index' (duration: 119.264286ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:54:10.712474Z","caller":"traceutil/trace.go:171","msg":"trace[355132156] transaction","detail":"{read_only:false; response_revision:20072; number_of_response:1; }","duration":"188.429039ms","start":"2026-05-05T22:54:10.524014Z","end":"2026-05-05T22:54:10.712443Z","steps":["trace[355132156] 'process raft request' (duration: 69.104331ms)","trace[355132156] 'compare' (duration: 118.652092ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:54:10.712571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.938034ms","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-05T22:54:10.71262Z","caller":"traceutil/trace.go:171","msg":"trace[173147705] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20072; }","duration":"146.022647ms","start":"2026-05-05T22:54:10.566581Z","end":"2026-05-05T22:54:10.712604Z","steps":["trace[173147705] 'agreement among raft nodes before linearized reading' (duration: 145.849062ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:54:10.977934Z","caller":"traceutil/trace.go:171","msg":"trace[1668149059] linearizableReadLoop","detail":"{readStateIndex:20750; appliedIndex:20748; }","duration":"113.698314ms","start":"2026-05-05T22:54:10.864213Z","end":"2026-05-05T22:54:10.977912Z","steps":["trace[1668149059] 'read index received' (duration: 59.191763ms)","trace[1668149059] 'applied index is now lower than readState.Index' (duration: 54.505821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:54:10.978136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.915229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-api-54db56479b-bmxgz.18accd27220e3848\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-05-05T22:54:10.978174Z","caller":"traceutil/trace.go:171","msg":"trace[387867981] range","detail":"{range_begin:/registry/events/openstack/heat-api-54db56479b-bmxgz.18accd27220e3848; range_end:; response_count:1; response_revision:20075; }","duration":"113.996401ms","start":"2026-05-05T22:54:10.864166Z","end":"2026-05-05T22:54:10.978162Z","steps":["trace[387867981] 'agreement among raft nodes before linearized reading' (duration: 113.851448ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:54:10.978419Z","caller":"traceutil/trace.go:171","msg":"trace[663633387] transaction","detail":"{read_only:false; response_revision:20074; number_of_response:1; }","duration":"137.183565ms","start":"2026-05-05T22:54:10.841225Z","end":"2026-05-05T22:54:10.978409Z","steps":["trace[663633387] 'process raft request' (duration: 82.165492ms)","trace[663633387] 'compare' (duration: 54.161683ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:54:10.978528Z","caller":"traceutil/trace.go:171","msg":"trace[1898758615] transaction","detail":"{read_only:false; response_revision:20075; number_of_response:1; }","duration":"137.186766ms","start":"2026-05-05T22:54:10.841333Z","end":"2026-05-05T22:54:10.97852Z","steps":["trace[1898758615] 'process raft request' (duration: 136.50761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:54:11.248135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.931626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T22:54:11.248316Z","caller":"traceutil/trace.go:171","msg":"trace[1468644744] transaction","detail":"{read_only:false; response_revision:20076; number_of_response:1; }","duration":"267.384534ms","start":"2026-05-05T22:54:10.980895Z","end":"2026-05-05T22:54:11.24828Z","steps":["trace[1468644744] 'process raft request' (duration: 141.203702ms)","trace[1468644744] 'compare' (duration: 125.795503ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:54:28.796437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18119} {"level":"info","ts":"2026-05-05T22:54:28.906818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18119,"took":"107.603728ms","hash":3238336300,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":29806592,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-05T22:54:28.906882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3238336300,"revision":18119,"compact-revision":15545} {"level":"warn","ts":"2026-05-05T22:57:54.100944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.953178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/cert-manager-test/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces/cert-manager-test0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T22:57:54.10106Z","caller":"traceutil/trace.go:171","msg":"trace[866891231] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/cert-manager-test/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces/cert-manager-test0; response_count:0; response_revision:22397; }","duration":"131.141502ms","start":"2026-05-05T22:57:53.969896Z","end":"2026-05-05T22:57:54.101037Z","steps":["trace[866891231] 'range keys from in-memory index tree' (duration: 130.864556ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:57:54.399018Z","caller":"traceutil/trace.go:171","msg":"trace[1624853855] transaction","detail":"{read_only:false; response_revision:22398; number_of_response:1; }","duration":"182.393245ms","start":"2026-05-05T22:57:54.216598Z","end":"2026-05-05T22:57:54.398991Z","steps":["trace[1624853855] 'process raft request' (duration: 182.225981ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:59:28.805985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20171} {"level":"info","ts":"2026-05-05T22:59:28.91428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20171,"took":"104.289047ms","hash":1879951734,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":42082304,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-05T22:59:28.914348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1879951734,"revision":20171,"compact-revision":18119} {"level":"warn","ts":"2026-05-05T23:01:29.137817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.309446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:29.137925Z","caller":"traceutil/trace.go:171","msg":"trace[271103256] linearizableReadLoop","detail":"{readStateIndex:25477; appliedIndex:25476; }","duration":"141.13657ms","start":"2026-05-05T23:01:28.996773Z","end":"2026-05-05T23:01:29.137909Z","steps":["trace[271103256] 'read index received' (duration: 64.161µs)","trace[271103256] 'applied index is now lower than readState.Index' (duration: 141.070929ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:29.13798Z","caller":"traceutil/trace.go:171","msg":"trace[910060709] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"327.958289ms","start":"2026-05-05T23:01:28.809995Z","end":"2026-05-05T23:01:29.137953Z","steps":["trace[910060709] 'process raft request' (duration: 151.440438ms)","trace[910060709] 'compare' (duration: 176.119102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:29.138109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.331765ms","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-05T23:01:29.138147Z","caller":"traceutil/trace.go:171","msg":"trace[1697329117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24673; }","duration":"141.406207ms","start":"2026-05-05T23:01:28.996729Z","end":"2026-05-05T23:01:29.138135Z","steps":["trace[1697329117] 'agreement among raft nodes before linearized reading' (duration: 141.264753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:29.138173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:28.809979Z","time spent":"328.101502ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:29.138367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.327277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:29.138402Z","caller":"traceutil/trace.go:171","msg":"trace[1058735030] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:24673; }","duration":"114.365888ms","start":"2026-05-05T23:01:29.024026Z","end":"2026-05-05T23:01:29.138392Z","steps":["trace[1058735030] 'agreement among raft nodes before linearized reading' (duration: 114.295076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:34.015459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.467899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:34.015593Z","caller":"traceutil/trace.go:171","msg":"trace[906787599] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"400.542463ms","start":"2026-05-05T23:01:33.615039Z","end":"2026-05-05T23:01:34.015582Z","steps":["trace[906787599] 'process raft request' (duration: 233.893559ms)","trace[906787599] 'compare' (duration: 166.160562ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:34.015638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.615019Z","time spent":"400.602805ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:34.015751Z","caller":"traceutil/trace.go:171","msg":"trace[57491954] linearizableReadLoop","detail":"{readStateIndex:25511; appliedIndex:25509; }","duration":"324.127896ms","start":"2026-05-05T23:01:33.691618Z","end":"2026-05-05T23:01:34.015746Z","steps":["trace[57491954] 'read index received' (duration: 157.3287ms)","trace[57491954] 'applied index is now lower than readState.Index' (duration: 166.798756ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:34.015821Z","caller":"traceutil/trace.go:171","msg":"trace[15044279] transaction","detail":"{read_only:false; response_revision:24706; number_of_response:1; }","duration":"377.848808ms","start":"2026-05-05T23:01:33.637967Z","end":"2026-05-05T23:01:34.015816Z","steps":["trace[15044279] 'process raft request' (duration: 377.559511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:34.015851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.637942Z","time spent":"377.89271ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:34.015967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.329581ms","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-05T23:01:34.015986Z","caller":"traceutil/trace.go:171","msg":"trace[1408361424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24706; }","duration":"324.394643ms","start":"2026-05-05T23:01:33.691585Z","end":"2026-05-05T23:01:34.015979Z","steps":["trace[1408361424] 'agreement among raft nodes before linearized reading' (duration: 324.303691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:34.015999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.691567Z","time spent":"324.429893ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T23:01:34.033535Z","caller":"traceutil/trace.go:171","msg":"trace[1511269505] transaction","detail":"{read_only:false; response_revision:24707; number_of_response:1; }","duration":"229.73682ms","start":"2026-05-05T23:01:33.803773Z","end":"2026-05-05T23:01:34.03351Z","steps":["trace[1511269505] 'process raft request' (duration: 229.527105ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:34.033734Z","caller":"traceutil/trace.go:171","msg":"trace[1177759538] transaction","detail":"{read_only:false; response_revision:24708; number_of_response:1; }","duration":"210.126968ms","start":"2026-05-05T23:01:33.823597Z","end":"2026-05-05T23:01:34.033724Z","steps":["trace[1177759538] 'process raft request' (duration: 209.866612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:34.287802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.609441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:34.288018Z","caller":"traceutil/trace.go:171","msg":"trace[1720299227] linearizableReadLoop","detail":"{readStateIndex:25514; appliedIndex:25511; }","duration":"272.23647ms","start":"2026-05-05T23:01:34.015765Z","end":"2026-05-05T23:01:34.288002Z","steps":["trace[1720299227] 'read index received' (duration: 17.532782ms)","trace[1720299227] 'applied index is now lower than readState.Index' (duration: 254.702758ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:34.288357Z","caller":"traceutil/trace.go:171","msg":"trace[1943764311] transaction","detail":"{read_only:false; response_revision:24709; number_of_response:1; }","duration":"320.520391ms","start":"2026-05-05T23:01:33.967816Z","end":"2026-05-05T23:01:34.288336Z","steps":["trace[1943764311] 'process raft request' (duration: 183.286824ms)","trace[1943764311] 'compare' (duration: 136.253873ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:34.288425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.967772Z","time spent":"320.629534ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:34.288553Z","caller":"traceutil/trace.go:171","msg":"trace[1865514881] transaction","detail":"{read_only:false; response_revision:24710; number_of_response:1; }","duration":"267.927377ms","start":"2026-05-05T23:01:34.020616Z","end":"2026-05-05T23:01:34.288543Z","steps":["trace[1865514881] 'process raft request' (duration: 267.301141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:34.288682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.076118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T23:01:34.288724Z","caller":"traceutil/trace.go:171","msg":"trace[1953300503] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:24710; }","duration":"429.13664ms","start":"2026-05-05T23:01:33.859567Z","end":"2026-05-05T23:01:34.288703Z","steps":["trace[1953300503] 'agreement among raft nodes before linearized reading' (duration: 429.075949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:34.288749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.85955Z","time spent":"429.191911ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":22,"response size":32,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:34.288934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.287849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7749"} {"level":"info","ts":"2026-05-05T23:01:34.288961Z","caller":"traceutil/trace.go:171","msg":"trace[1913339653] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24710; }","duration":"470.35467ms","start":"2026-05-05T23:01:33.818601Z","end":"2026-05-05T23:01:34.288956Z","steps":["trace[1913339653] 'agreement among raft nodes before linearized reading' (duration: 470.262038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:34.288975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:33.81858Z","time spent":"470.392341ms","remote":"127.0.0.1:49752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7773,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-05-05T23:01:34.289112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.319137ms","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-05T23:01:34.289137Z","caller":"traceutil/trace.go:171","msg":"trace[1987459819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24710; }","duration":"228.373197ms","start":"2026-05-05T23:01:34.060758Z","end":"2026-05-05T23:01:34.289131Z","steps":["trace[1987459819] 'agreement among raft nodes before linearized reading' (duration: 228.311136ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:34.496153Z","caller":"traceutil/trace.go:171","msg":"trace[28300840] transaction","detail":"{read_only:false; response_revision:24712; number_of_response:1; }","duration":"168.071018ms","start":"2026-05-05T23:01:34.32806Z","end":"2026-05-05T23:01:34.496131Z","steps":["trace[28300840] 'process raft request' (duration: 106.833817ms)","trace[28300840] 'compare' (duration: 61.064477ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:34.82442Z","caller":"traceutil/trace.go:171","msg":"trace[910535368] transaction","detail":"{read_only:false; response_revision:24713; number_of_response:1; }","duration":"283.89269ms","start":"2026-05-05T23:01:34.540502Z","end":"2026-05-05T23:01:34.824394Z","steps":["trace[910535368] 'process raft request' (duration: 283.713645ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:34.824619Z","caller":"traceutil/trace.go:171","msg":"trace[691545168] linearizableReadLoop","detail":"{readStateIndex:25518; appliedIndex:25518; }","duration":"279.132717ms","start":"2026-05-05T23:01:34.545464Z","end":"2026-05-05T23:01:34.824597Z","steps":["trace[691545168] 'read index received' (duration: 279.121106ms)","trace[691545168] 'applied index is now lower than readState.Index' (duration: 9.62µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:34.824746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.27781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:34.824769Z","caller":"traceutil/trace.go:171","msg":"trace[107883659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24713; }","duration":"279.351611ms","start":"2026-05-05T23:01:34.545411Z","end":"2026-05-05T23:01:34.824763Z","steps":["trace[107883659] 'agreement among raft nodes before linearized reading' (duration: 279.255669ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:34.829602Z","caller":"traceutil/trace.go:171","msg":"trace[1937490172] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"278.496492ms","start":"2026-05-05T23:01:34.551083Z","end":"2026-05-05T23:01:34.829579Z","steps":["trace[1937490172] 'process raft request' (duration: 277.953839ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:35.170442Z","caller":"traceutil/trace.go:171","msg":"trace[1322462471] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"247.955047ms","start":"2026-05-05T23:01:34.922462Z","end":"2026-05-05T23:01:35.170417Z","steps":["trace[1322462471] 'process raft request' (duration: 247.743301ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:35.313218Z","caller":"traceutil/trace.go:171","msg":"trace[1985613862] transaction","detail":"{read_only:false; response_revision:24718; number_of_response:1; }","duration":"112.944644ms","start":"2026-05-05T23:01:35.200248Z","end":"2026-05-05T23:01:35.313192Z","steps":["trace[1985613862] 'process raft request' (duration: 110.799733ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:35.80542Z","caller":"traceutil/trace.go:171","msg":"trace[824887599] transaction","detail":"{read_only:false; response_revision:24719; number_of_response:1; }","duration":"109.323746ms","start":"2026-05-05T23:01:35.696077Z","end":"2026-05-05T23:01:35.805401Z","steps":["trace[824887599] 'process raft request' (duration: 109.202483ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:36.197607Z","caller":"traceutil/trace.go:171","msg":"trace[1867346322] transaction","detail":"{read_only:false; response_revision:24720; number_of_response:1; }","duration":"176.253495ms","start":"2026-05-05T23:01:36.021326Z","end":"2026-05-05T23:01:36.197579Z","steps":["trace[1867346322] 'process raft request' (duration: 176.07594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.341389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.249159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:36.341722Z","caller":"traceutil/trace.go:171","msg":"trace[851654362] linearizableReadLoop","detail":"{readStateIndex:25526; appliedIndex:25524; }","duration":"311.880284ms","start":"2026-05-05T23:01:36.029825Z","end":"2026-05-05T23:01:36.341705Z","steps":["trace[851654362] 'read index received' (duration: 167.581027ms)","trace[851654362] 'applied index is now lower than readState.Index' (duration: 144.298337ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:36.341776Z","caller":"traceutil/trace.go:171","msg":"trace[924136760] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"303.525054ms","start":"2026-05-05T23:01:36.038207Z","end":"2026-05-05T23:01:36.341732Z","steps":["trace[924136760] 'process raft request' (duration: 303.37476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.341883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.030447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:36.341923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:36.038187Z","time spent":"303.660036ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:36.34196Z","caller":"traceutil/trace.go:171","msg":"trace[166133105] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24722; }","duration":"312.149861ms","start":"2026-05-05T23:01:36.029792Z","end":"2026-05-05T23:01:36.341942Z","steps":["trace[166133105] 'agreement among raft nodes before linearized reading' (duration: 312.016577ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:36.341926Z","caller":"traceutil/trace.go:171","msg":"trace[609951139] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"319.854416ms","start":"2026-05-05T23:01:36.02205Z","end":"2026-05-05T23:01:36.341905Z","steps":["trace[609951139] 'process raft request' (duration: 183.997371ms)","trace[609951139] 'compare' (duration: 135.039134ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:36.342125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:36.022023Z","time spent":"320.05543ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:36.342008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:36.029778Z","time spent":"312.219682ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:36.349729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.510445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T23:01:36.349809Z","caller":"traceutil/trace.go:171","msg":"trace[1456548351] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24723; }","duration":"212.644618ms","start":"2026-05-05T23:01:36.137147Z","end":"2026-05-05T23:01:36.349792Z","steps":["trace[1456548351] 'agreement among raft nodes before linearized reading' (duration: 212.440353ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:36.550783Z","caller":"traceutil/trace.go:171","msg":"trace[508962366] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"197.811812ms","start":"2026-05-05T23:01:36.35293Z","end":"2026-05-05T23:01:36.550742Z","steps":["trace[508962366] 'process raft request' (duration: 139.871161ms)","trace[508962366] 'compare' (duration: 57.580923ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:36.865038Z","caller":"traceutil/trace.go:171","msg":"trace[536736419] linearizableReadLoop","detail":"{readStateIndex:25530; appliedIndex:25529; }","duration":"364.714102ms","start":"2026-05-05T23:01:36.50029Z","end":"2026-05-05T23:01:36.865005Z","steps":["trace[536736419] 'read index received' (duration: 324.781183ms)","trace[536736419] 'applied index is now lower than readState.Index' (duration: 39.931829ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:36.865149Z","caller":"traceutil/trace.go:171","msg":"trace[1554592023] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"511.346754ms","start":"2026-05-05T23:01:36.35379Z","end":"2026-05-05T23:01:36.865136Z","steps":["trace[1554592023] 'process raft request' (duration: 471.237731ms)","trace[1554592023] 'compare' (duration: 39.808156ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:36.865269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.953088ms","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-05T23:01:36.865287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:36.353765Z","time spent":"511.416226ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:36.86532Z","caller":"traceutil/trace.go:171","msg":"trace[77622615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24725; }","duration":"365.057551ms","start":"2026-05-05T23:01:36.500252Z","end":"2026-05-05T23:01:36.86531Z","steps":["trace[77622615] 'agreement among raft nodes before linearized reading' (duration: 364.933547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.865349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:36.500234Z","time spent":"365.110342ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T23:01:36.867993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.758218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:36.868043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.599082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:36.868068Z","caller":"traceutil/trace.go:171","msg":"trace[959112606] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:24727; }","duration":"228.650453ms","start":"2026-05-05T23:01:36.639411Z","end":"2026-05-05T23:01:36.868061Z","steps":["trace[959112606] 'agreement among raft nodes before linearized reading' (duration: 228.604662ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:36.868063Z","caller":"traceutil/trace.go:171","msg":"trace[1179067754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24727; }","duration":"323.86936ms","start":"2026-05-05T23:01:36.544176Z","end":"2026-05-05T23:01:36.868045Z","steps":["trace[1179067754] 'agreement among raft nodes before linearized reading' (duration: 323.732417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.868106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:36.544144Z","time spent":"323.953234ms","remote":"127.0.0.1:35432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T23:01:36.868162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.610417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:36.86818Z","caller":"traceutil/trace.go:171","msg":"trace[528277786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24727; }","duration":"152.629097ms","start":"2026-05-05T23:01:36.715546Z","end":"2026-05-05T23:01:36.868176Z","steps":["trace[528277786] 'agreement among raft nodes before linearized reading' (duration: 152.601367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:36.868354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.186515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T23:01:36.868401Z","caller":"traceutil/trace.go:171","msg":"trace[3551254] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24727; }","duration":"231.271826ms","start":"2026-05-05T23:01:36.637117Z","end":"2026-05-05T23:01:36.868389Z","steps":["trace[3551254] 'agreement among raft nodes before linearized reading' (duration: 231.194395ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:37.035468Z","caller":"traceutil/trace.go:171","msg":"trace[915913748] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"149.071491ms","start":"2026-05-05T23:01:36.886362Z","end":"2026-05-05T23:01:37.035434Z","steps":["trace[915913748] 'process raft request' (duration: 127.77357ms)","trace[915913748] 'compare' (duration: 20.917702ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:37.19207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.684595ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"warn","ts":"2026-05-05T23:01:37.633907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.058047ms","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":"warn","ts":"2026-05-05T23:01:37.633938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.983366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:37.633993Z","caller":"traceutil/trace.go:171","msg":"trace[1160887775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24730; }","duration":"317.19725ms","start":"2026-05-05T23:01:37.316776Z","end":"2026-05-05T23:01:37.633973Z","steps":["trace[1160887775] 'range keys from in-memory index tree' (duration: 316.857382ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:37.634009Z","caller":"traceutil/trace.go:171","msg":"trace[253481942] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24730; }","duration":"317.117349ms","start":"2026-05-05T23:01:37.316874Z","end":"2026-05-05T23:01:37.633991Z","steps":["trace[253481942] 'count revisions from in-memory index tree' (duration: 316.866582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:37.634036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:37.316757Z","time spent":"317.269432ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T23:01:37.634046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:37.316853Z","time spent":"317.18574ms","remote":"127.0.0.1:49764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:37.634225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.843238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:37.634254Z","caller":"traceutil/trace.go:171","msg":"trace[1298387591] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24730; }","duration":"305.906429ms","start":"2026-05-05T23:01:37.328339Z","end":"2026-05-05T23:01:37.634246Z","steps":["trace[1298387591] 'count revisions from in-memory index tree' (duration: 305.755436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:37.634274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:37.328321Z","time spent":"305.94709ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:37.634282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.83178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:37.634354Z","caller":"traceutil/trace.go:171","msg":"trace[425264855] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24730; }","duration":"179.933573ms","start":"2026-05-05T23:01:37.454395Z","end":"2026-05-05T23:01:37.634329Z","steps":["trace[425264855] 'count revisions from in-memory index tree' (duration: 179.761469ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:37.884088Z","caller":"traceutil/trace.go:171","msg":"trace[1514083941] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"245.384555ms","start":"2026-05-05T23:01:37.638649Z","end":"2026-05-05T23:01:37.884033Z","steps":["trace[1514083941] 'process raft request' (duration: 245.229101ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:37.970343Z","caller":"traceutil/trace.go:171","msg":"trace[381145370] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"159.40385ms","start":"2026-05-05T23:01:37.810917Z","end":"2026-05-05T23:01:37.970321Z","steps":["trace[381145370] 'process raft request' (duration: 159.360599ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:37.970355Z","caller":"traceutil/trace.go:171","msg":"trace[152434160] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"320.004068ms","start":"2026-05-05T23:01:37.65032Z","end":"2026-05-05T23:01:37.970324Z","steps":["trace[152434160] 'process raft request' (duration: 319.22553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:37.9705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:37.650297Z","time spent":"320.11979ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:38.765503Z","caller":"traceutil/trace.go:171","msg":"trace[576792781] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"418.204967ms","start":"2026-05-05T23:01:38.347274Z","end":"2026-05-05T23:01:38.765479Z","steps":["trace[576792781] 'process raft request' (duration: 418.017752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:38.765654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:38.347252Z","time spent":"418.32333ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:38.805899Z","caller":"traceutil/trace.go:171","msg":"trace[2071992899] linearizableReadLoop","detail":"{readStateIndex:25544; appliedIndex:25541; }","duration":"448.786652ms","start":"2026-05-05T23:01:38.357091Z","end":"2026-05-05T23:01:38.805878Z","steps":["trace[2071992899] 'read index received' (duration: 408.248519ms)","trace[2071992899] 'applied index is now lower than readState.Index' (duration: 40.537193ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:38.805946Z","caller":"traceutil/trace.go:171","msg":"trace[402745915] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"457.8897ms","start":"2026-05-05T23:01:38.348026Z","end":"2026-05-05T23:01:38.805916Z","steps":["trace[402745915] 'process raft request' (duration: 457.675635ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:38.806018Z","caller":"traceutil/trace.go:171","msg":"trace[1325948616] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"451.383405ms","start":"2026-05-05T23:01:38.354598Z","end":"2026-05-05T23:01:38.805982Z","steps":["trace[1325948616] 'process raft request' (duration: 451.237721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:38.806061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.945756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:38.806103Z","caller":"traceutil/trace.go:171","msg":"trace[329803816] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24738; }","duration":"449.033608ms","start":"2026-05-05T23:01:38.357057Z","end":"2026-05-05T23:01:38.80609Z","steps":["trace[329803816] 'agreement among raft nodes before linearized reading' (duration: 448.944676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:38.806101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:38.348009Z","time spent":"457.998043ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:38.806139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:38.357044Z","time spent":"449.084629ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:38.806131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.394273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:38.80617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.080529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:38.806194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:38.35458Z","time spent":"451.517677ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:38.806212Z","caller":"traceutil/trace.go:171","msg":"trace[34840887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24738; }","duration":"262.533657ms","start":"2026-05-05T23:01:38.543658Z","end":"2026-05-05T23:01:38.806192Z","steps":["trace[34840887] 'agreement among raft nodes before linearized reading' (duration: 262.375113ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:38.806258Z","caller":"traceutil/trace.go:171","msg":"trace[825462824] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24738; }","duration":"106.194392ms","start":"2026-05-05T23:01:38.700039Z","end":"2026-05-05T23:01:38.806233Z","steps":["trace[825462824] 'agreement among raft nodes before linearized reading' (duration: 106.019477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:38.806373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.375079ms","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-05T23:01:38.806515Z","caller":"traceutil/trace.go:171","msg":"trace[510823978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24738; }","duration":"251.529013ms","start":"2026-05-05T23:01:38.554915Z","end":"2026-05-05T23:01:38.806444Z","steps":["trace[510823978] 'agreement among raft nodes before linearized reading' (duration: 251.256296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:39.098549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.324069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:39.098716Z","caller":"traceutil/trace.go:171","msg":"trace[1369443706] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"289.189878ms","start":"2026-05-05T23:01:38.809501Z","end":"2026-05-05T23:01:39.098691Z","steps":["trace[1369443706] 'process raft request' (duration: 119.578872ms)","trace[1369443706] 'compare' (duration: 169.096073ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:39.099035Z","caller":"traceutil/trace.go:171","msg":"trace[974967382] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"227.643189ms","start":"2026-05-05T23:01:38.871364Z","end":"2026-05-05T23:01:39.099008Z","steps":["trace[974967382] 'process raft request' (duration: 227.304861ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:39.099158Z","caller":"traceutil/trace.go:171","msg":"trace[622672382] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"226.00603ms","start":"2026-05-05T23:01:38.87312Z","end":"2026-05-05T23:01:39.099126Z","steps":["trace[622672382] 'process raft request' (duration: 225.627981ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:39.099197Z","caller":"traceutil/trace.go:171","msg":"trace[819172597] linearizableReadLoop","detail":"{readStateIndex:25545; appliedIndex:25544; }","duration":"229.744119ms","start":"2026-05-05T23:01:38.869435Z","end":"2026-05-05T23:01:39.09918Z","steps":["trace[819172597] 'read index received' (duration: 59.603111ms)","trace[819172597] 'applied index is now lower than readState.Index' (duration: 170.139838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:39.099384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.342508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-05T23:01:39.099422Z","caller":"traceutil/trace.go:171","msg":"trace[902633327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24743; }","duration":"216.403549ms","start":"2026-05-05T23:01:38.883008Z","end":"2026-05-05T23:01:39.099412Z","steps":["trace[902633327] 'agreement among raft nodes before linearized reading' (duration: 216.258455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:39.099422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.968615ms","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-05T23:01:39.099488Z","caller":"traceutil/trace.go:171","msg":"trace[1464247465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24743; }","duration":"230.078617ms","start":"2026-05-05T23:01:38.869391Z","end":"2026-05-05T23:01:39.09947Z","steps":["trace[1464247465] 'agreement among raft nodes before linearized reading' (duration: 229.867562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:39.512493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.959308ms","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-05T23:01:39.512576Z","caller":"traceutil/trace.go:171","msg":"trace[1060450932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24745; }","duration":"293.078851ms","start":"2026-05-05T23:01:39.219475Z","end":"2026-05-05T23:01:39.512554Z","steps":["trace[1060450932] 'range keys from in-memory index tree' (duration: 292.793614ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:39.626091Z","caller":"traceutil/trace.go:171","msg":"trace[1386916583] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"109.439859ms","start":"2026-05-05T23:01:39.516624Z","end":"2026-05-05T23:01:39.626064Z","steps":["trace[1386916583] 'process raft request' (duration: 108.812145ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:47.036046Z","caller":"traceutil/trace.go:171","msg":"trace[1905379033] transaction","detail":"{read_only:false; response_revision:24796; number_of_response:1; }","duration":"240.743544ms","start":"2026-05-05T23:01:46.79527Z","end":"2026-05-05T23:01:47.036013Z","steps":["trace[1905379033] 'process raft request' (duration: 240.459357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:47.631978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.219349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:47.632199Z","caller":"traceutil/trace.go:171","msg":"trace[842006532] transaction","detail":"{read_only:false; response_revision:24797; number_of_response:1; }","duration":"794.617681ms","start":"2026-05-05T23:01:46.837551Z","end":"2026-05-05T23:01:47.632168Z","steps":["trace[842006532] 'process raft request' (duration: 475.086934ms)","trace[842006532] 'compare' (duration: 318.929383ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:47.632348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:46.837523Z","time spent":"794.742044ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:47.632746Z","caller":"traceutil/trace.go:171","msg":"trace[1543648538] transaction","detail":"{read_only:false; response_revision:24798; number_of_response:1; }","duration":"794.99524ms","start":"2026-05-05T23:01:46.837731Z","end":"2026-05-05T23:01:47.632727Z","steps":["trace[1543648538] 'process raft request' (duration: 794.345155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:47.632853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:46.837723Z","time spent":"795.077402ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:47.939126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.939784ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-05T23:01:47.939424Z","caller":"traceutil/trace.go:171","msg":"trace[609421371] linearizableReadLoop","detail":"{readStateIndex:25606; appliedIndex:25603; }","duration":"851.214301ms","start":"2026-05-05T23:01:47.08819Z","end":"2026-05-05T23:01:47.939404Z","steps":["trace[609421371] 'read index received' (duration: 224.484363ms)","trace[609421371] 'applied index is now lower than readState.Index' (duration: 626.728878ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:47.939603Z","caller":"traceutil/trace.go:171","msg":"trace[433312892] transaction","detail":"{read_only:false; response_revision:24799; number_of_response:1; }","duration":"813.420983ms","start":"2026-05-05T23:01:47.126157Z","end":"2026-05-05T23:01:47.939578Z","steps":["trace[433312892] 'process raft request' (duration: 813.130726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:47.939792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.126131Z","time spent":"813.538756ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:48.245663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157434587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:48.245872Z","caller":"traceutil/trace.go:171","msg":"trace[501155140] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24799; }","duration":"1.157703354s","start":"2026-05-05T23:01:47.088137Z","end":"2026-05-05T23:01:48.24584Z","steps":["trace[501155140] 'agreement among raft nodes before linearized reading' (duration: 851.367414ms)","trace[501155140] 'count revisions from in-memory index tree' (duration: 306.068694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:48.245938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.088115Z","time spent":"1.157804736s","remote":"127.0.0.1:55526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:48.246212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.581106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:48.246402Z","caller":"traceutil/trace.go:171","msg":"trace[149585762] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"234.603527ms","start":"2026-05-05T23:01:48.011788Z","end":"2026-05-05T23:01:48.246391Z","steps":["trace[149585762] 'process raft request' (duration: 234.568996ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:48.246623Z","caller":"traceutil/trace.go:171","msg":"trace[794677074] linearizableReadLoop","detail":"{readStateIndex:25609; appliedIndex:25606; }","duration":"307.150359ms","start":"2026-05-05T23:01:47.939464Z","end":"2026-05-05T23:01:48.246615Z","steps":["trace[794677074] 'read index received' (duration: 51.851µs)","trace[794677074] 'applied index is now lower than readState.Index' (duration: 307.097708ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:48.24659Z","caller":"traceutil/trace.go:171","msg":"trace[2146316977] transaction","detail":"{read_only:false; response_revision:24800; number_of_response:1; }","duration":"1.12004682s","start":"2026-05-05T23:01:47.126517Z","end":"2026-05-05T23:01:48.246564Z","steps":["trace[2146316977] 'process raft request' (duration: 813.044194ms)","trace[2146316977] 'compare' (duration: 306.243298ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:48.246761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.126478Z","time spent":"1.120186543s","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:48.246814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09260269s","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-05T23:01:48.246839Z","caller":"traceutil/trace.go:171","msg":"trace[218467893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24802; }","duration":"1.092658041s","start":"2026-05-05T23:01:47.154174Z","end":"2026-05-05T23:01:48.246832Z","steps":["trace[218467893] 'agreement among raft nodes before linearized reading' (duration: 1.092558519s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.246857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.154157Z","time spent":"1.092695862s","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T23:01:48.246594Z","caller":"traceutil/trace.go:171","msg":"trace[2123557418] transaction","detail":"{read_only:false; response_revision:24801; number_of_response:1; }","duration":"1.118344679s","start":"2026-05-05T23:01:47.128217Z","end":"2026-05-05T23:01:48.246562Z","steps":["trace[2123557418] 'process raft request' (duration: 1.118064192s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.24696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.128187Z","time spent":"1.118710247s","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:48.246829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11011758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:48.247186Z","caller":"traceutil/trace.go:171","msg":"trace[765800289] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24802; }","duration":"1.110452648s","start":"2026-05-05T23:01:47.136631Z","end":"2026-05-05T23:01:48.247083Z","steps":["trace[765800289] 'agreement among raft nodes before linearized reading' (duration: 1.11012025s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.247245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.136601Z","time spent":"1.110627572s","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":30,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:48.247258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.075746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:48.247285Z","caller":"traceutil/trace.go:171","msg":"trace[927028580] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24802; }","duration":"503.139959ms","start":"2026-05-05T23:01:47.744138Z","end":"2026-05-05T23:01:48.247278Z","steps":["trace[927028580] 'agreement among raft nodes before linearized reading' (duration: 503.085506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.247307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.744108Z","time spent":"503.19438ms","remote":"127.0.0.1:55514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:48.247315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.74993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:48.247432Z","caller":"traceutil/trace.go:171","msg":"trace[1102805663] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24802; }","duration":"594.906153ms","start":"2026-05-05T23:01:47.652478Z","end":"2026-05-05T23:01:48.247384Z","steps":["trace[1102805663] 'agreement among raft nodes before linearized reading' (duration: 594.744238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.247439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.302594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T23:01:48.247488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.652447Z","time spent":"595.027806ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-05T23:01:48.247493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.407752ms","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-05T23:01:48.247505Z","caller":"traceutil/trace.go:171","msg":"trace[891751822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24802; }","duration":"573.447717ms","start":"2026-05-05T23:01:47.674038Z","end":"2026-05-05T23:01:48.247486Z","steps":["trace[891751822] 'agreement among raft nodes before linearized reading' (duration: 573.19023ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:48.247546Z","caller":"traceutil/trace.go:171","msg":"trace[384117210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24802; }","duration":"321.506405ms","start":"2026-05-05T23:01:47.926026Z","end":"2026-05-05T23:01:48.247533Z","steps":["trace[384117210] 'agreement among raft nodes before linearized reading' (duration: 321.276339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.247555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.674007Z","time spent":"573.53673ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T23:01:48.247552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.928753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:48.247577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.926003Z","time spent":"321.568115ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T23:01:48.247613Z","caller":"traceutil/trace.go:171","msg":"trace[806530340] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24802; }","duration":"277.027296ms","start":"2026-05-05T23:01:47.970566Z","end":"2026-05-05T23:01:48.247593Z","steps":["trace[806530340] 'agreement among raft nodes before linearized reading' (duration: 276.926784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.2477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.901347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:48.24771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.208698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:01:48.247729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.929613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:48.247753Z","caller":"traceutil/trace.go:171","msg":"trace[1667060723] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24802; }","duration":"697.966598ms","start":"2026-05-05T23:01:47.549768Z","end":"2026-05-05T23:01:48.247734Z","steps":["trace[1667060723] 'agreement among raft nodes before linearized reading' (duration: 697.896117ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:48.24777Z","caller":"traceutil/trace.go:171","msg":"trace[1547405085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24802; }","duration":"703.993594ms","start":"2026-05-05T23:01:47.543769Z","end":"2026-05-05T23:01:48.247763Z","steps":["trace[1547405085] 'agreement among raft nodes before linearized reading' (duration: 703.929162ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:48.247754Z","caller":"traceutil/trace.go:171","msg":"trace[1487608896] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24802; }","duration":"679.2721ms","start":"2026-05-05T23:01:47.568467Z","end":"2026-05-05T23:01:48.247739Z","steps":["trace[1487608896] 'agreement among raft nodes before linearized reading' (duration: 679.211359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.247793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.543755Z","time spent":"704.034016ms","remote":"127.0.0.1:35448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T23:01:48.247789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.947022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-05-05T23:01:48.247801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.568456Z","time spent":"679.336352ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-05-05T23:01:48.247799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.549758Z","time spent":"698.028641ms","remote":"127.0.0.1:37840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-05T23:01:48.24782Z","caller":"traceutil/trace.go:171","msg":"trace[1406992700] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24802; }","duration":"848.004384ms","start":"2026-05-05T23:01:47.399808Z","end":"2026-05-05T23:01:48.247813Z","steps":["trace[1406992700] 'agreement among raft nodes before linearized reading' (duration: 847.925582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.247843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.399792Z","time spent":"848.043115ms","remote":"127.0.0.1:35602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T23:01:48.247729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113103183s","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-05T23:01:48.247952Z","caller":"traceutil/trace.go:171","msg":"trace[150993106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24802; }","duration":"1.113341399s","start":"2026-05-05T23:01:47.13458Z","end":"2026-05-05T23:01:48.247921Z","steps":["trace[150993106] 'agreement among raft nodes before linearized reading' (duration: 1.112869708s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.248116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.134565Z","time spent":"1.11342306s","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T23:01:48.248148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093357208s","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-05T23:01:48.248248Z","caller":"traceutil/trace.go:171","msg":"trace[234673452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24802; }","duration":"1.093530073s","start":"2026-05-05T23:01:47.154693Z","end":"2026-05-05T23:01:48.248223Z","steps":["trace[234673452] 'agreement among raft nodes before linearized reading' (duration: 1.092038227s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.248312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:47.154659Z","time spent":"1.093642576s","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T23:01:48.801623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.303976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:48.801899Z","caller":"traceutil/trace.go:171","msg":"trace[1527345837] linearizableReadLoop","detail":"{readStateIndex:25615; appliedIndex:25611; }","duration":"532.399001ms","start":"2026-05-05T23:01:48.269484Z","end":"2026-05-05T23:01:48.801883Z","steps":["trace[1527345837] 'read index received' (duration: 79.745667ms)","trace[1527345837] 'applied index is now lower than readState.Index' (duration: 452.650474ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:48.801958Z","caller":"traceutil/trace.go:171","msg":"trace[1885871474] transaction","detail":"{read_only:false; response_revision:24806; number_of_response:1; }","duration":"549.081872ms","start":"2026-05-05T23:01:48.252847Z","end":"2026-05-05T23:01:48.801929Z","steps":["trace[1885871474] 'process raft request' (duration: 548.913268ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:48.80201Z","caller":"traceutil/trace.go:171","msg":"trace[1870819362] transaction","detail":"{read_only:false; response_revision:24807; number_of_response:1; }","duration":"548.392235ms","start":"2026-05-05T23:01:48.253594Z","end":"2026-05-05T23:01:48.801986Z","steps":["trace[1870819362] 'process raft request' (duration: 548.21944ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:48.80207Z","caller":"traceutil/trace.go:171","msg":"trace[126156363] transaction","detail":"{read_only:false; response_revision:24804; number_of_response:1; }","duration":"549.469642ms","start":"2026-05-05T23:01:48.25258Z","end":"2026-05-05T23:01:48.802049Z","steps":["trace[126156363] 'process raft request' (duration: 96.637452ms)","trace[126156363] 'compare' (duration: 452.153243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:48.802099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:48.253589Z","time spent":"548.475387ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:48.802138Z","caller":"traceutil/trace.go:171","msg":"trace[453203835] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"549.468152ms","start":"2026-05-05T23:01:48.252661Z","end":"2026-05-05T23:01:48.802129Z","steps":["trace[453203835] 'process raft request' (duration: 549.050101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.802147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:48.252568Z","time spent":"549.540233ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:48.80219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:48.252656Z","time spent":"549.506883ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:48.802097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:48.252842Z","time spent":"549.182595ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T23:01:48.802312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.352413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:48.802374Z","caller":"traceutil/trace.go:171","msg":"trace[857111971] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:24808; }","duration":"545.423064ms","start":"2026-05-05T23:01:48.256933Z","end":"2026-05-05T23:01:48.802356Z","steps":["trace[857111971] 'agreement among raft nodes before linearized reading' (duration: 545.283491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.802411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:48.256911Z","time spent":"545.491086ms","remote":"127.0.0.1:35494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":30,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"info","ts":"2026-05-05T23:01:48.802402Z","caller":"traceutil/trace.go:171","msg":"trace[419238722] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"495.040934ms","start":"2026-05-05T23:01:48.30733Z","end":"2026-05-05T23:01:48.802371Z","steps":["trace[419238722] 'process raft request' (duration: 494.52185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.802551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:48.307301Z","time spent":"495.163307ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:01:48.802545Z","caller":"traceutil/trace.go:171","msg":"trace[1615552458] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"214.78238ms","start":"2026-05-05T23:01:48.587745Z","end":"2026-05-05T23:01:48.802527Z","steps":["trace[1615552458] 'process raft request' (duration: 214.576605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.802598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.407024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:01:48.802645Z","caller":"traceutil/trace.go:171","msg":"trace[1388605760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24809; }","duration":"257.467296ms","start":"2026-05-05T23:01:48.545164Z","end":"2026-05-05T23:01:48.802632Z","steps":["trace[1388605760] 'agreement among raft nodes before linearized reading' (duration: 257.380504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.802716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.72168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-05T23:01:48.802721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.666014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18621"} {"level":"info","ts":"2026-05-05T23:01:48.802774Z","caller":"traceutil/trace.go:171","msg":"trace[127913573] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:24809; }","duration":"219.828382ms","start":"2026-05-05T23:01:48.582932Z","end":"2026-05-05T23:01:48.802761Z","steps":["trace[127913573] 'agreement among raft nodes before linearized reading' (duration: 219.555365ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:48.802792Z","caller":"traceutil/trace.go:171","msg":"trace[658739840] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24809; }","duration":"531.788686ms","start":"2026-05-05T23:01:48.270984Z","end":"2026-05-05T23:01:48.802773Z","steps":["trace[658739840] 'agreement among raft nodes before linearized reading' (duration: 531.476869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:48.802844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:48.270968Z","time spent":"531.860968ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18645,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-05T23:01:49.193413Z","caller":"traceutil/trace.go:171","msg":"trace[2075117224] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"389.019486ms","start":"2026-05-05T23:01:48.804349Z","end":"2026-05-05T23:01:49.193368Z","steps":["trace[2075117224] 'process raft request' (duration: 376.455085ms)","trace[2075117224] 'compare' (duration: 11.908956ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:49.193615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:48.804326Z","time spent":"389.19081ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1633,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T23:01:49.193656Z","caller":"traceutil/trace.go:171","msg":"trace[1247608665] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"151.442288ms","start":"2026-05-05T23:01:49.04219Z","end":"2026-05-05T23:01:49.193632Z","steps":["trace[1247608665] 'process raft request' (duration: 151.139481ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:49.193726Z","caller":"traceutil/trace.go:171","msg":"trace[1741012088] linearizableReadLoop","detail":"{readStateIndex:25618; appliedIndex:25617; }","duration":"329.346351ms","start":"2026-05-05T23:01:48.864349Z","end":"2026-05-05T23:01:49.193695Z","steps":["trace[1741012088] 'read index received' (duration: 316.418051ms)","trace[1741012088] 'applied index is now lower than readState.Index' (duration: 12.92616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:49.193948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.576238ms","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-05T23:01:49.194Z","caller":"traceutil/trace.go:171","msg":"trace[110757362] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24812; }","duration":"329.679749ms","start":"2026-05-05T23:01:48.864309Z","end":"2026-05-05T23:01:49.193989Z","steps":["trace[110757362] 'agreement among raft nodes before linearized reading' (duration: 329.564896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:49.194035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:48.864292Z","time spent":"329.734281ms","remote":"127.0.0.1:35886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-05T23:01:50.589175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.4297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-05-05T23:01:50.589284Z","caller":"traceutil/trace.go:171","msg":"trace[1515337159] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:24875; }","duration":"102.560655ms","start":"2026-05-05T23:01:50.486679Z","end":"2026-05-05T23:01:50.589239Z","steps":["trace[1515337159] 'range keys from in-memory index tree' (duration: 102.253926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:50.589352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.465256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c12bc536-9aff-4393-9e76-4ede2b281666\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-05-05T23:01:50.589375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.746302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c12bc536-9aff-4393-9e76-4ede2b281666\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-05T23:01:50.589403Z","caller":"traceutil/trace.go:171","msg":"trace[1879304182] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c12bc536-9aff-4393-9e76-4ede2b281666; range_end:; response_count:1; response_revision:24875; }","duration":"242.560388ms","start":"2026-05-05T23:01:50.34683Z","end":"2026-05-05T23:01:50.589391Z","steps":["trace[1879304182] 'range keys from in-memory index tree' (duration: 242.283431ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:50.589429Z","caller":"traceutil/trace.go:171","msg":"trace[1115619738] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c12bc536-9aff-4393-9e76-4ede2b281666; range_end:; response_count:1; response_revision:24875; }","duration":"155.840985ms","start":"2026-05-05T23:01:50.43357Z","end":"2026-05-05T23:01:50.589411Z","steps":["trace[1115619738] 'range keys from in-memory index tree' (duration: 155.62291ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:52.61642Z","caller":"traceutil/trace.go:171","msg":"trace[1425712280] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"121.767585ms","start":"2026-05-05T23:01:52.494625Z","end":"2026-05-05T23:01:52.616392Z","steps":["trace[1425712280] 'process raft request' (duration: 121.485449ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:01:57.20465Z","caller":"traceutil/trace.go:171","msg":"trace[1971264869] linearizableReadLoop","detail":"{readStateIndex:25756; appliedIndex:25755; }","duration":"252.333872ms","start":"2026-05-05T23:01:56.952291Z","end":"2026-05-05T23:01:57.204624Z","steps":["trace[1971264869] 'read index received' (duration: 221.752398ms)","trace[1971264869] 'applied index is now lower than readState.Index' (duration: 30.579834ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:57.204763Z","caller":"traceutil/trace.go:171","msg":"trace[1864361982] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"291.697068ms","start":"2026-05-05T23:01:56.913027Z","end":"2026-05-05T23:01:57.204724Z","steps":["trace[1864361982] 'process raft request' (duration: 261.01134ms)","trace[1864361982] 'compare' (duration: 30.151595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:57.204903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.603588ms","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-05T23:01:57.204937Z","caller":"traceutil/trace.go:171","msg":"trace[489051160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24946; }","duration":"252.69139ms","start":"2026-05-05T23:01:56.952235Z","end":"2026-05-05T23:01:57.204927Z","steps":["trace[489051160] 'agreement among raft nodes before linearized reading' (duration: 252.528017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:57.426578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.282026ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-05T23:01:57.426796Z","caller":"traceutil/trace.go:171","msg":"trace[192753612] linearizableReadLoop","detail":"{readStateIndex:25757; appliedIndex:25756; }","duration":"222.039124ms","start":"2026-05-05T23:01:57.204738Z","end":"2026-05-05T23:01:57.426777Z","steps":["trace[192753612] 'read index received' (duration: 10.608025ms)","trace[192753612] 'applied index is now lower than readState.Index' (duration: 211.430069ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:01:57.426919Z","caller":"traceutil/trace.go:171","msg":"trace[859784251] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"217.449753ms","start":"2026-05-05T23:01:57.209446Z","end":"2026-05-05T23:01:57.426896Z","steps":["trace[859784251] 'process raft request' (duration: 217.235708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:01:57.555216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.542383ms","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-05T23:01:57.555294Z","caller":"traceutil/trace.go:171","msg":"trace[2118458443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24947; }","duration":"601.645174ms","start":"2026-05-05T23:01:56.95363Z","end":"2026-05-05T23:01:57.555275Z","steps":["trace[2118458443] 'agreement among raft nodes before linearized reading' (duration: 473.186168ms)","trace[2118458443] 'range keys from in-memory index tree' (duration: 128.280572ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:57.555332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:56.95362Z","time spent":"601.702996ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T23:01:57.55536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.366812ms","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-05T23:01:57.555502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.571399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:01:57.555493Z","caller":"traceutil/trace.go:171","msg":"trace[705482361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24947; }","duration":"602.590457ms","start":"2026-05-05T23:01:56.952875Z","end":"2026-05-05T23:01:57.555465Z","steps":["trace[705482361] 'agreement among raft nodes before linearized reading' (duration: 473.958547ms)","trace[705482361] 'range keys from in-memory index tree' (duration: 128.361194ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:57.555552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:56.952849Z","time spent":"602.68942ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T23:01:57.555677Z","caller":"traceutil/trace.go:171","msg":"trace[1687661745] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"342.366075ms","start":"2026-05-05T23:01:57.21328Z","end":"2026-05-05T23:01:57.555647Z","steps":["trace[1687661745] 'process raft request' (duration: 213.605152ms)","trace[1687661745] 'compare' (duration: 128.227301ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:01:57.555813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:01:57.213246Z","time spent":"342.490748ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:02:17.705974Z","caller":"traceutil/trace.go:171","msg":"trace[1353151847] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"192.432013ms","start":"2026-05-05T23:02:17.513513Z","end":"2026-05-05T23:02:17.705945Z","steps":["trace[1353151847] 'process raft request' (duration: 192.136016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:17.706186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.559021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:02:17.706277Z","caller":"traceutil/trace.go:171","msg":"trace[1922058837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25119; }","duration":"161.664793ms","start":"2026-05-05T23:02:17.544571Z","end":"2026-05-05T23:02:17.706236Z","steps":["trace[1922058837] 'agreement among raft nodes before linearized reading' (duration: 161.565201ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:17.70598Z","caller":"traceutil/trace.go:171","msg":"trace[475284356] linearizableReadLoop","detail":"{readStateIndex:25935; appliedIndex:25934; }","duration":"161.284644ms","start":"2026-05-05T23:02:17.544613Z","end":"2026-05-05T23:02:17.705898Z","steps":["trace[475284356] 'read index received' (duration: 161.029238ms)","trace[475284356] 'applied index is now lower than readState.Index' (duration: 254.056µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:17.898027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.875258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:02:17.898308Z","caller":"traceutil/trace.go:171","msg":"trace[1856464780] linearizableReadLoop","detail":"{readStateIndex:25936; appliedIndex:25935; }","duration":"191.390278ms","start":"2026-05-05T23:02:17.706897Z","end":"2026-05-05T23:02:17.898287Z","steps":["trace[1856464780] 'read index received' (duration: 79.293525ms)","trace[1856464780] 'applied index is now lower than readState.Index' (duration: 112.094573ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:17.898325Z","caller":"traceutil/trace.go:171","msg":"trace[766797533] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"264.395022ms","start":"2026-05-05T23:02:17.63388Z","end":"2026-05-05T23:02:17.898275Z","steps":["trace[766797533] 'process raft request' (duration: 152.188216ms)","trace[766797533] 'compare' (duration: 111.742375ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:17.898341Z","caller":"traceutil/trace.go:171","msg":"trace[1988060498] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"154.526873ms","start":"2026-05-05T23:02:17.743795Z","end":"2026-05-05T23:02:17.898322Z","steps":["trace[1988060498] 'process raft request' (duration: 154.42587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:17.898535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.992818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T23:02:17.898591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.467603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-05T23:02:17.898591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.068048ms","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-05T23:02:17.898623Z","caller":"traceutil/trace.go:171","msg":"trace[1548485765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25122; }","duration":"239.546805ms","start":"2026-05-05T23:02:17.659068Z","end":"2026-05-05T23:02:17.898615Z","steps":["trace[1548485765] 'agreement among raft nodes before linearized reading' (duration: 239.223107ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:17.898613Z","caller":"traceutil/trace.go:171","msg":"trace[521655479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25122; }","duration":"191.144682ms","start":"2026-05-05T23:02:17.707449Z","end":"2026-05-05T23:02:17.898594Z","steps":["trace[521655479] 'agreement among raft nodes before linearized reading' (duration: 191.029209ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:17.898602Z","caller":"traceutil/trace.go:171","msg":"trace[1900352441] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"148.943459ms","start":"2026-05-05T23:02:17.749627Z","end":"2026-05-05T23:02:17.89857Z","steps":["trace[1900352441] 'process raft request' (duration: 148.660342ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:17.898662Z","caller":"traceutil/trace.go:171","msg":"trace[1155542829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25122; }","duration":"245.161079ms","start":"2026-05-05T23:02:17.653481Z","end":"2026-05-05T23:02:17.898642Z","steps":["trace[1155542829] 'agreement among raft nodes before linearized reading' (duration: 244.921884ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:18.210069Z","caller":"traceutil/trace.go:171","msg":"trace[825181976] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"168.208652ms","start":"2026-05-05T23:02:18.041838Z","end":"2026-05-05T23:02:18.210047Z","steps":["trace[825181976] 'process raft request' (duration: 168.12441ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:18.2103Z","caller":"traceutil/trace.go:171","msg":"trace[2047686323] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"307.001305ms","start":"2026-05-05T23:02:17.903267Z","end":"2026-05-05T23:02:18.210268Z","steps":["trace[2047686323] 'process raft request' (duration: 280.166771ms)","trace[2047686323] 'compare' (duration: 26.167938ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:18.210464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T23:02:17.903248Z","time spent":"307.138759ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T23:02:18.483925Z","caller":"traceutil/trace.go:171","msg":"trace[811512261] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"136.364866ms","start":"2026-05-05T23:02:18.347541Z","end":"2026-05-05T23:02:18.483906Z","steps":["trace[811512261] 'process raft request' (duration: 53.932366ms)","trace[811512261] 'compare' (duration: 82.355059ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:18.54738Z","caller":"traceutil/trace.go:171","msg":"trace[404544038] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"199.703399ms","start":"2026-05-05T23:02:18.347651Z","end":"2026-05-05T23:02:18.547354Z","steps":["trace[404544038] 'process raft request' (duration: 199.564456ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:18.547539Z","caller":"traceutil/trace.go:171","msg":"trace[846254866] linearizableReadLoop","detail":"{readStateIndex:25944; appliedIndex:25941; }","duration":"156.206593ms","start":"2026-05-05T23:02:18.391307Z","end":"2026-05-05T23:02:18.547513Z","steps":["trace[846254866] 'read index received' (duration: 10.182015ms)","trace[846254866] 'applied index is now lower than readState.Index' (duration: 146.021488ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:18.547576Z","caller":"traceutil/trace.go:171","msg":"trace[670509503] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"190.691161ms","start":"2026-05-05T23:02:18.356876Z","end":"2026-05-05T23:02:18.547567Z","steps":["trace[670509503] 'process raft request' (duration: 190.446485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:18.547705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.379568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-05T23:02:18.547729Z","caller":"traceutil/trace.go:171","msg":"trace[1704098615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25128; }","duration":"156.450019ms","start":"2026-05-05T23:02:18.391272Z","end":"2026-05-05T23:02:18.547722Z","steps":["trace[1704098615] 'agreement among raft nodes before linearized reading' (duration: 156.319236ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:18.752908Z","caller":"traceutil/trace.go:171","msg":"trace[149700646] linearizableReadLoop","detail":"{readStateIndex:25945; appliedIndex:25944; }","duration":"167.041783ms","start":"2026-05-05T23:02:18.585843Z","end":"2026-05-05T23:02:18.752885Z","steps":["trace[149700646] 'read index received' (duration: 166.821518ms)","trace[149700646] 'applied index is now lower than readState.Index' (duration: 219.425µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:18.75297Z","caller":"traceutil/trace.go:171","msg":"trace[976346257] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"201.817469ms","start":"2026-05-05T23:02:18.551125Z","end":"2026-05-05T23:02:18.752942Z","steps":["trace[976346257] 'process raft request' (duration: 201.603814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:18.753048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.185817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:02:18.753088Z","caller":"traceutil/trace.go:171","msg":"trace[338693182] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25129; }","duration":"167.282989ms","start":"2026-05-05T23:02:18.585791Z","end":"2026-05-05T23:02:18.753074Z","steps":["trace[338693182] 'agreement among raft nodes before linearized reading' (duration: 167.194357ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:19.989974Z","caller":"traceutil/trace.go:171","msg":"trace[1703749067] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"263.032099ms","start":"2026-05-05T23:02:19.726901Z","end":"2026-05-05T23:02:19.989933Z","steps":["trace[1703749067] 'process raft request' (duration: 198.590151ms)","trace[1703749067] 'compare' (duration: 64.022047ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:19.990198Z","caller":"traceutil/trace.go:171","msg":"trace[1396204764] linearizableReadLoop","detail":"{readStateIndex:25950; appliedIndex:25949; }","duration":"207.28513ms","start":"2026-05-05T23:02:19.782897Z","end":"2026-05-05T23:02:19.990182Z","steps":["trace[1396204764] 'read index received' (duration: 142.607046ms)","trace[1396204764] 'applied index is now lower than readState.Index' (duration: 64.677234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:19.990322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.419423ms","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-05T23:02:19.990348Z","caller":"traceutil/trace.go:171","msg":"trace[1797239847] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25137; }","duration":"207.458674ms","start":"2026-05-05T23:02:19.782882Z","end":"2026-05-05T23:02:19.99034Z","steps":["trace[1797239847] 'agreement among raft nodes before linearized reading' (duration: 207.383082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:19.990395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.842931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T23:02:19.990443Z","caller":"traceutil/trace.go:171","msg":"trace[1338760614] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25137; }","duration":"169.939373ms","start":"2026-05-05T23:02:19.820492Z","end":"2026-05-05T23:02:19.990432Z","steps":["trace[1338760614] 'agreement among raft nodes before linearized reading' (duration: 169.841401ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:20.37408Z","caller":"traceutil/trace.go:171","msg":"trace[854487566] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"155.354472ms","start":"2026-05-05T23:02:20.218691Z","end":"2026-05-05T23:02:20.374046Z","steps":["trace[854487566] 'process raft request' (duration: 144.174304ms)","trace[854487566] 'compare' (duration: 10.990224ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:20.670637Z","caller":"traceutil/trace.go:171","msg":"trace[1834964865] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"182.314871ms","start":"2026-05-05T23:02:20.4883Z","end":"2026-05-05T23:02:20.670614Z","steps":["trace[1834964865] 'process raft request' (duration: 103.960998ms)","trace[1834964865] 'compare' (duration: 78.086935ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:20.670689Z","caller":"traceutil/trace.go:171","msg":"trace[1730420356] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"118.737743ms","start":"2026-05-05T23:02:20.551931Z","end":"2026-05-05T23:02:20.670669Z","steps":["trace[1730420356] 'process raft request' (duration: 118.63378ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:20.670819Z","caller":"traceutil/trace.go:171","msg":"trace[525405242] linearizableReadLoop","detail":"{readStateIndex:25959; appliedIndex:25956; }","duration":"104.670345ms","start":"2026-05-05T23:02:20.566124Z","end":"2026-05-05T23:02:20.670795Z","steps":["trace[525405242] 'read index received' (duration: 26.131508ms)","trace[525405242] 'applied index is now lower than readState.Index' (duration: 78.537557ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:20.670917Z","caller":"traceutil/trace.go:171","msg":"trace[1660377416] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"118.906227ms","start":"2026-05-05T23:02:20.551979Z","end":"2026-05-05T23:02:20.670885Z","steps":["trace[1660377416] 'process raft request' (duration: 118.653761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:20.670948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.810559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:02:20.671032Z","caller":"traceutil/trace.go:171","msg":"trace[1198020907] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:25143; }","duration":"104.941822ms","start":"2026-05-05T23:02:20.566077Z","end":"2026-05-05T23:02:20.671019Z","steps":["trace[1198020907] 'agreement among raft nodes before linearized reading' (duration: 104.808739ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:21.160719Z","caller":"traceutil/trace.go:171","msg":"trace[957200428] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"119.730927ms","start":"2026-05-05T23:02:21.040963Z","end":"2026-05-05T23:02:21.160694Z","steps":["trace[957200428] 'process raft request' (duration: 119.589153ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:26.389054Z","caller":"traceutil/trace.go:171","msg":"trace[1571028623] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"141.407298ms","start":"2026-05-05T23:02:26.247612Z","end":"2026-05-05T23:02:26.38902Z","steps":["trace[1571028623] 'process raft request' (duration: 141.219643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:28.311142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.381589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T23:02:28.311319Z","caller":"traceutil/trace.go:171","msg":"trace[1436756034] linearizableReadLoop","detail":"{readStateIndex:26014; appliedIndex:26013; }","duration":"178.54375ms","start":"2026-05-05T23:02:28.132758Z","end":"2026-05-05T23:02:28.311302Z","steps":["trace[1436756034] 'read index received' (duration: 43.817593ms)","trace[1436756034] 'applied index is now lower than readState.Index' (duration: 134.724787ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:02:28.311387Z","caller":"traceutil/trace.go:171","msg":"trace[446726549] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"216.593773ms","start":"2026-05-05T23:02:28.094766Z","end":"2026-05-05T23:02:28.31136Z","steps":["trace[446726549] 'process raft request' (duration: 81.877256ms)","trace[446726549] 'compare' (duration: 133.952609ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:28.311448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.665572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:02:28.311489Z","caller":"traceutil/trace.go:171","msg":"trace[782414072] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25196; }","duration":"178.754125ms","start":"2026-05-05T23:02:28.132724Z","end":"2026-05-05T23:02:28.311478Z","steps":["trace[782414072] 'agreement among raft nodes before linearized reading' (duration: 178.655302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:02:28.311498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.547712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:02:28.311603Z","caller":"traceutil/trace.go:171","msg":"trace[223130616] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25196; }","duration":"124.668325ms","start":"2026-05-05T23:02:28.186909Z","end":"2026-05-05T23:02:28.311577Z","steps":["trace[223130616] 'agreement among raft nodes before linearized reading' (duration: 124.505061ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:35.334646Z","caller":"traceutil/trace.go:171","msg":"trace[166498044] linearizableReadLoop","detail":"{readStateIndex:26067; appliedIndex:26066; }","duration":"125.191118ms","start":"2026-05-05T23:02:35.209437Z","end":"2026-05-05T23:02:35.334628Z","steps":["trace[166498044] 'read index received' (duration: 125.029855ms)","trace[166498044] 'applied index is now lower than readState.Index' (duration: 160.653µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T23:02:35.33477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.308651ms","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-05T23:02:35.334801Z","caller":"traceutil/trace.go:171","msg":"trace[615455520] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25248; }","duration":"125.383092ms","start":"2026-05-05T23:02:35.209409Z","end":"2026-05-05T23:02:35.334792Z","steps":["trace[615455520] 'agreement among raft nodes before linearized reading' (duration: 125.29396ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:02:35.335087Z","caller":"traceutil/trace.go:171","msg":"trace[2044179533] transaction","detail":"{read_only:false; response_revision:25248; number_of_response:1; }","duration":"133.470696ms","start":"2026-05-05T23:02:35.201609Z","end":"2026-05-05T23:02:35.33508Z","steps":["trace[2044179533] 'process raft request' (duration: 132.913173ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:03:00.268845Z","caller":"traceutil/trace.go:171","msg":"trace[1852066953] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"203.137631ms","start":"2026-05-05T23:03:00.065687Z","end":"2026-05-05T23:03:00.268825Z","steps":["trace[1852066953] 'process raft request' (duration: 203.006618ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:03:00.292776Z","caller":"traceutil/trace.go:171","msg":"trace[1102184555] transaction","detail":"{read_only:false; response_revision:25580; number_of_response:1; }","duration":"152.072743ms","start":"2026-05-05T23:03:00.140682Z","end":"2026-05-05T23:03:00.292755Z","steps":["trace[1102184555] 'process raft request' (duration: 142.77985ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:03:00.293296Z","caller":"traceutil/trace.go:171","msg":"trace[2109457364] transaction","detail":"{read_only:false; response_revision:25581; number_of_response:1; }","duration":"149.435121ms","start":"2026-05-05T23:03:00.143851Z","end":"2026-05-05T23:03:00.293287Z","steps":["trace[2109457364] 'process raft request' (duration: 148.867717ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:03:00.293842Z","caller":"traceutil/trace.go:171","msg":"trace[1071074690] linearizableReadLoop","detail":"{readStateIndex:26405; appliedIndex:26403; }","duration":"151.973331ms","start":"2026-05-05T23:03:00.141849Z","end":"2026-05-05T23:03:00.293822Z","steps":["trace[1071074690] 'read index received' (duration: 126.838097ms)","trace[1071074690] 'applied index is now lower than readState.Index' (duration: 25.134264ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T23:03:00.293936Z","caller":"traceutil/trace.go:171","msg":"trace[615230796] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"142.028162ms","start":"2026-05-05T23:03:00.151896Z","end":"2026-05-05T23:03:00.293924Z","steps":["trace[615230796] 'process raft request' (duration: 141.821357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:03:00.293975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.114565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:03:00.29402Z","caller":"traceutil/trace.go:171","msg":"trace[1947622268] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25582; }","duration":"152.223448ms","start":"2026-05-05T23:03:00.14178Z","end":"2026-05-05T23:03:00.294004Z","steps":["trace[1947622268] 'agreement among raft nodes before linearized reading' (duration: 152.136226ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:03:56.546711Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006928.wal"} {"level":"info","ts":"2026-05-05T23:04:28.82247Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23409} {"level":"info","ts":"2026-05-05T23:04:28.951245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23409,"took":"124.393687ms","hash":239679433,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":36864000,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-05T23:04:28.9513Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":239679433,"revision":23409,"compact-revision":20171} {"level":"warn","ts":"2026-05-05T23:05:42.700806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.639536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-05-05T23:05:42.700905Z","caller":"traceutil/trace.go:171","msg":"trace[284968537] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27205; }","duration":"130.790419ms","start":"2026-05-05T23:05:42.570095Z","end":"2026-05-05T23:05:42.700886Z","steps":["trace[284968537] 'range keys from in-memory index tree' (duration: 130.510983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:05:42.700886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.75685ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:05:42.701016Z","caller":"traceutil/trace.go:171","msg":"trace[1977176824] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:27205; }","duration":"198.898653ms","start":"2026-05-05T23:05:42.502097Z","end":"2026-05-05T23:05:42.700996Z","steps":["trace[1977176824] 'range keys from in-memory index tree' (duration: 198.736469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T23:05:42.701123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.626203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T23:05:42.701222Z","caller":"traceutil/trace.go:171","msg":"trace[2123665170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27205; }","duration":"158.762537ms","start":"2026-05-05T23:05:42.542436Z","end":"2026-05-05T23:05:42.701198Z","steps":["trace[2123665170] 'range keys from in-memory index tree' (duration: 158.569622ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T23:09:28.82917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26365} {"level":"info","ts":"2026-05-05T23:09:28.977384Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26365,"took":"143.984693ms","hash":3729674730,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":33890304,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T23:09:28.977459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3729674730,"revision":26365,"compact-revision":23409} {"level":"info","ts":"2026-05-05T23:09:41.622301Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T23:09:41.627787Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-05T23:09:41.627882Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-05T23:14:28.835323Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28993} {"level":"info","ts":"2026-05-05T23:14:28.975012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28993,"took":"135.384632ms","hash":1500956057,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-05T23:14:28.975079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1500956057,"revision":28993,"compact-revision":26365} {"level":"info","ts":"2026-05-05T23:19:28.843209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31210} {"level":"info","ts":"2026-05-05T23:19:28.983551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31210,"took":"136.055868ms","hash":198395628,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":30556160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T23:19:28.983631Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":198395628,"revision":31210,"compact-revision":28993} {"level":"info","ts":"2026-05-05T23:24:28.849149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33512} {"level":"info","ts":"2026-05-05T23:24:29.005008Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33512,"took":"149.891734ms","hash":1526303764,"current-db-size-bytes":60071936,"current-db-size":"60 MB","current-db-size-in-use-bytes":32251904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T23:24:29.00507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1526303764,"revision":33512,"compact-revision":31210} {"level":"info","ts":"2026-05-05T23:29:28.854299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35771} {"level":"info","ts":"2026-05-05T23:29:28.987114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35771,"took":"130.028535ms","hash":2700965934,"current-db-size-bytes":61382656,"current-db-size":"61 MB","current-db-size-in-use-bytes":31182848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T23:29:28.987171Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2700965934,"revision":35771,"compact-revision":33512} {"level":"info","ts":"2026-05-05T23:31:06.291112Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T23:31:06.296299Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-05T23:31:06.296398Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}