{"level":"warn","ts":"2026-07-25T01:10:26.855236Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T01:10:26.855372Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-07-25T01:10:26.855486Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T01:10:26.855507Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-25T01:10:26.855529Z","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-07-25T01:10:26.8564Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-07-25T01:10:26.856502Z","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":8,"max-cpu-available":8,"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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-07-25T01:10:26.860668Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.891071ms"} {"level":"info","ts":"2026-07-25T01:10:26.870046Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-07-25T01:10:26.870187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T01:10:26.870226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-07-25T01:10:26.870248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T01:10:26.870272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-07-25T01:10:26.870333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-07-25T01:10:26.87799Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T01:10:26.879728Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T01:10:26.882268Z","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-07-25T01:10:26.886289Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T01:10:26.890207Z","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-07-25T01:10:26.8904Z","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-07-25T01:10:26.890453Z","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-07-25T01:10:26.894039Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T01:10:26.894642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-07-25T01:10:26.894692Z","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-07-25T01:10:26.894762Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-25T01:10:26.895039Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T01:10:26.895081Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T01:10:26.895559Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-25T01:10:26.895596Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-25T01:10:27.270933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T01:10:27.271015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T01:10:27.271109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-07-25T01:10:27.271142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-07-25T01:10:27.271193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-25T01:10:27.271246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-07-25T01:10:27.271279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-25T01:10:27.27243Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T01:10:27.27245Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T01:10:27.272501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T01:10:27.272778Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T01:10:27.272855Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T01:10:27.275899Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-07-25T01:10:27.276911Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T01:10:27.282935Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T01:10:27.285477Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T01:10:27.285719Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T01:10:27.285835Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T01:10:30.340543Z","caller":"traceutil/trace.go:171","msg":"trace[1748963240] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"125.214051ms","start":"2026-07-25T01:10:30.215297Z","end":"2026-07-25T01:10:30.340511Z","steps":["trace[1748963240] 'read index received' (duration: 114.917085ms)","trace[1748963240] 'applied index is now lower than readState.Index' (duration: 10.295546ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:10:30.340869Z","caller":"traceutil/trace.go:171","msg":"trace[1274540289] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"127.118201ms","start":"2026-07-25T01:10:30.21373Z","end":"2026-07-25T01:10:30.340848Z","steps":["trace[1274540289] 'process raft request' (duration: 116.611461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:10:30.341124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.795353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-25T01:10:30.341316Z","caller":"traceutil/trace.go:171","msg":"trace[1333525964] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"126.012238ms","start":"2026-07-25T01:10:30.215271Z","end":"2026-07-25T01:10:30.341283Z","steps":["trace[1333525964] 'agreement among raft nodes before linearized reading' (duration: 125.643281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:10:31.300977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.051443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-25T01:10:31.301114Z","caller":"traceutil/trace.go:171","msg":"trace[1911458920] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"877.237252ms","start":"2026-07-25T01:10:30.423854Z","end":"2026-07-25T01:10:31.301091Z","steps":["trace[1911458920] 'process raft request' (duration: 321.378672ms)","trace[1911458920] 'compare' (duration: 554.815708ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:10:31.301189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:10:30.423816Z","time spent":"877.342875ms","remote":"127.0.0.1:56686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:10:31.890951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948390122,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T01:10:32.147373Z","caller":"traceutil/trace.go:171","msg":"trace[245727748] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"757.500193ms","start":"2026-07-25T01:10:31.389817Z","end":"2026-07-25T01:10:32.147318Z","steps":["trace[245727748] 'read index received' (duration: 757.256798ms)","trace[245727748] 'applied index is now lower than readState.Index' (duration: 242.085µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:10:32.147528Z","caller":"traceutil/trace.go:171","msg":"trace[1340389580] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"839.983203ms","start":"2026-07-25T01:10:31.307404Z","end":"2026-07-25T01:10:32.147388Z","steps":["trace[1340389580] 'process raft request' (duration: 839.686846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:10:32.147629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.778429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-25T01:10:32.147717Z","caller":"traceutil/trace.go:171","msg":"trace[277638606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"757.924713ms","start":"2026-07-25T01:10:31.389776Z","end":"2026-07-25T01:10:32.147701Z","steps":["trace[277638606] 'agreement among raft nodes before linearized reading' (duration: 757.692288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:10:32.147773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:10:31.307375Z","time spent":"840.284729ms","remote":"127.0.0.1:56686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:10:32.147801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:10:31.389716Z","time spent":"758.074386ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T01:10:32.41081Z","caller":"traceutil/trace.go:171","msg":"trace[642304982] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"256.311039ms","start":"2026-07-25T01:10:32.15447Z","end":"2026-07-25T01:10:32.410781Z","steps":["trace[642304982] 'process raft request' (duration: 254.708823ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:07.756713Z","caller":"traceutil/trace.go:171","msg":"trace[567904654] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"712.422849ms","start":"2026-07-25T01:11:07.044255Z","end":"2026-07-25T01:11:07.756678Z","steps":["trace[567904654] 'process raft request' (duration: 712.260163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:07.75689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.044232Z","time spent":"712.574846ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T01:11:07.915016Z","caller":"traceutil/trace.go:171","msg":"trace[1131617242] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"859.123527ms","start":"2026-07-25T01:11:07.055847Z","end":"2026-07-25T01:11:07.914971Z","steps":["trace[1131617242] 'process raft request' (duration: 858.770443ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:07.915088Z","caller":"traceutil/trace.go:171","msg":"trace[1302254591] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"858.605716ms","start":"2026-07-25T01:11:07.056421Z","end":"2026-07-25T01:11:07.915027Z","steps":["trace[1302254591] 'process raft request' (duration: 858.356286ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:07.915112Z","caller":"traceutil/trace.go:171","msg":"trace[1615759470] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"838.46977ms","start":"2026-07-25T01:11:07.0766Z","end":"2026-07-25T01:11:07.915069Z","steps":["trace[1615759470] 'process raft request' (duration: 838.332784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:07.915175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.055826Z","time spent":"859.274823ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:11:07.915241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.076574Z","time spent":"838.593705ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:11:07.915248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.056415Z","time spent":"858.752522ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T01:11:07.915373Z","caller":"traceutil/trace.go:171","msg":"trace[1445152833] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"826.169187ms","start":"2026-07-25T01:11:07.089187Z","end":"2026-07-25T01:11:07.915356Z","steps":["trace[1445152833] 'process raft request' (duration: 825.920447ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:07.915382Z","caller":"traceutil/trace.go:171","msg":"trace[1936472619] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"834.997431ms","start":"2026-07-25T01:11:07.080365Z","end":"2026-07-25T01:11:07.915363Z","steps":["trace[1936472619] 'process raft request' (duration: 834.613925ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:07.915353Z","caller":"traceutil/trace.go:171","msg":"trace[1159382641] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"827.015971ms","start":"2026-07-25T01:11:07.0883Z","end":"2026-07-25T01:11:07.915316Z","steps":["trace[1159382641] 'process raft request' (duration: 826.75022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:07.915451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.080357Z","time spent":"835.059913ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:11:07.915451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.089169Z","time spent":"826.24311ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:11:07.915524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.08827Z","time spent":"827.165706ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T01:11:07.915604Z","caller":"traceutil/trace.go:171","msg":"trace[924627893] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"812.973259ms","start":"2026-07-25T01:11:07.102615Z","end":"2026-07-25T01:11:07.915588Z","steps":["trace[924627893] 'process raft request' (duration: 812.647546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:07.91569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.102608Z","time spent":"813.043721ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T01:11:07.915666Z","caller":"traceutil/trace.go:171","msg":"trace[1594555963] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:506; }","duration":"655.721869ms","start":"2026-07-25T01:11:07.259899Z","end":"2026-07-25T01:11:07.91562Z","steps":["trace[1594555963] 'read index received' (duration: 496.659817ms)","trace[1594555963] 'applied index is now lower than readState.Index' (duration: 159.060682ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:11:07.915766Z","caller":"traceutil/trace.go:171","msg":"trace[1764366433] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"784.831581ms","start":"2026-07-25T01:11:07.130915Z","end":"2026-07-25T01:11:07.915747Z","steps":["trace[1764366433] 'process raft request' (duration: 784.492887ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:07.915796Z","caller":"traceutil/trace.go:171","msg":"trace[1854549800] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"671.916427ms","start":"2026-07-25T01:11:07.243868Z","end":"2026-07-25T01:11:07.915784Z","steps":["trace[1854549800] 'process raft request' (duration: 671.683898ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:07.9158Z","caller":"traceutil/trace.go:171","msg":"trace[1004671988] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"809.227738ms","start":"2026-07-25T01:11:07.106559Z","end":"2026-07-25T01:11:07.915786Z","steps":["trace[1004671988] 'process raft request' (duration: 808.77761ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:07.915822Z","caller":"traceutil/trace.go:171","msg":"trace[389041649] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"824.543702ms","start":"2026-07-25T01:11:07.091261Z","end":"2026-07-25T01:11:07.915804Z","steps":["trace[389041649] 'process raft request' (duration: 823.893916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:07.915827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.130868Z","time spent":"784.930955ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:11:07.915869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.10655Z","time spent":"809.2818ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:11:07.9159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.091253Z","time spent":"824.607805ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:11:07.91591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.243831Z","time spent":"671.99821ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:11:07.916406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.462348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:11:07.916452Z","caller":"traceutil/trace.go:171","msg":"trace[1315012355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"656.570463ms","start":"2026-07-25T01:11:07.25987Z","end":"2026-07-25T01:11:07.91644Z","steps":["trace[1315012355] 'agreement among raft nodes before linearized reading' (duration: 656.038921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:07.916514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.25986Z","time spent":"656.645155ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T01:11:08.050174Z","caller":"traceutil/trace.go:171","msg":"trace[2089865121] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:518; }","duration":"134.395631ms","start":"2026-07-25T01:11:07.915743Z","end":"2026-07-25T01:11:08.050139Z","steps":["trace[2089865121] 'read index received' (duration: 118.505668ms)","trace[2089865121] 'applied index is now lower than readState.Index' (duration: 15.888723ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:11:08.050447Z","caller":"traceutil/trace.go:171","msg":"trace[1260762010] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"285.458723ms","start":"2026-07-25T01:11:07.764935Z","end":"2026-07-25T01:11:08.050393Z","steps":["trace[1260762010] 'process raft request' (duration: 285.036866ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:08.050506Z","caller":"traceutil/trace.go:171","msg":"trace[1454118528] transaction","detail":"{read_only:false; number_of_response:1; response_revision:502; }","duration":"285.113389ms","start":"2026-07-25T01:11:07.765378Z","end":"2026-07-25T01:11:08.050491Z","steps":["trace[1454118528] 'process raft request' (duration: 284.667381ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:08.050687Z","caller":"traceutil/trace.go:171","msg":"trace[1547208843] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"286.756016ms","start":"2026-07-25T01:11:07.763918Z","end":"2026-07-25T01:11:08.050674Z","steps":["trace[1547208843] 'process raft request' (duration: 270.312801ms)","trace[1547208843] 'compare' (duration: 15.538908ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:11:08.050887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.844424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T01:11:08.050933Z","caller":"traceutil/trace.go:171","msg":"trace[120878112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:502; }","duration":"377.955069ms","start":"2026-07-25T01:11:07.672966Z","end":"2026-07-25T01:11:08.050921Z","steps":["trace[120878112] 'agreement among raft nodes before linearized reading' (duration: 377.763851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:08.050974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.672935Z","time spent":"378.029201ms","remote":"127.0.0.1:56632","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-07-25T01:11:08.551102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948391039,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T01:11:09.181813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.995087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:11:09.182646Z","caller":"traceutil/trace.go:171","msg":"trace[1414739371] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.245693363s","start":"2026-07-25T01:11:07.93689Z","end":"2026-07-25T01:11:09.182584Z","steps":["trace[1414739371] 'process raft request' (duration: 1.245494354s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.182738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.936875Z","time spent":"1.245829629s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T01:11:09.183336Z","caller":"traceutil/trace.go:171","msg":"trace[2042141932] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.251794257s","start":"2026-07-25T01:11:07.93144Z","end":"2026-07-25T01:11:09.183234Z","steps":["trace[2042141932] 'process raft request' (duration: 1.250551236s)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:09.18382Z","caller":"traceutil/trace.go:171","msg":"trace[73763272] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.252135012s","start":"2026-07-25T01:11:07.931635Z","end":"2026-07-25T01:11:09.18377Z","steps":["trace[73763272] 'process raft request' (duration: 1.250671962s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.183677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.931423Z","time spent":"1.251948444s","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:09.187169Z","caller":"traceutil/trace.go:171","msg":"trace[981363773] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.249865494s","start":"2026-07-25T01:11:07.937053Z","end":"2026-07-25T01:11:09.186918Z","steps":["trace[981363773] 'process raft request' (duration: 1.245479174s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.18741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.937026Z","time spent":"1.2502845s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T01:11:09.184059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.931613Z","time spent":"1.25233247s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T01:11:09.183933Z","caller":"traceutil/trace.go:171","msg":"trace[1342812749] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.25484998s","start":"2026-07-25T01:11:07.928371Z","end":"2026-07-25T01:11:09.183221Z","steps":["trace[1342812749] 'process raft request' (duration: 746.346631ms)","trace[1342812749] 'compare' (duration: 506.061259ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:11:09.187985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.928351Z","time spent":"1.259607514s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T01:11:09.187272Z","caller":"traceutil/trace.go:171","msg":"trace[1135111767] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.243253379s","start":"2026-07-25T01:11:07.943985Z","end":"2026-07-25T01:11:09.187238Z","steps":["trace[1135111767] 'process raft request' (duration: 1.241158243s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.188653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.94397Z","time spent":"1.244613694s","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:09.189229Z","caller":"traceutil/trace.go:171","msg":"trace[274705107] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.245624305s","start":"2026-07-25T01:11:07.943218Z","end":"2026-07-25T01:11:09.188842Z","steps":["trace[274705107] 'process raft request' (duration: 1.240785498s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.189358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.943189Z","time spent":"1.246070733s","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:09.190149Z","caller":"traceutil/trace.go:171","msg":"trace[1510774411] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.244924298s","start":"2026-07-25T01:11:07.945196Z","end":"2026-07-25T01:11:09.190121Z","steps":["trace[1510774411] 'process raft request' (duration: 1.241076881s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.190616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.945174Z","time spent":"1.245012741s","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:09.19235Z","caller":"traceutil/trace.go:171","msg":"trace[1468262329] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.206418686s","start":"2026-07-25T01:11:07.985917Z","end":"2026-07-25T01:11:09.192335Z","steps":["trace[1468262329] 'process raft request' (duration: 1.205930456s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.192399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.985891Z","time spent":"1.206484289s","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T01:11:09.192511Z","caller":"traceutil/trace.go:171","msg":"trace[198562107] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.134501825s","start":"2026-07-25T01:11:08.057983Z","end":"2026-07-25T01:11:09.192485Z","steps":["trace[198562107] 'process raft request' (duration: 1.134159321s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.19264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:08.057959Z","time spent":"1.13460746s","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:09.192724Z","caller":"traceutil/trace.go:171","msg":"trace[1235375717] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.136862401s","start":"2026-07-25T01:11:08.055852Z","end":"2026-07-25T01:11:09.192714Z","steps":["trace[1235375717] 'process raft request' (duration: 1.136238865s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.192769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:08.05582Z","time spent":"1.136926933s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:09.192772Z","caller":"traceutil/trace.go:171","msg":"trace[923660480] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.133906252s","start":"2026-07-25T01:11:08.058858Z","end":"2026-07-25T01:11:09.192764Z","steps":["trace[923660480] 'process raft request' (duration: 1.133321008s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.19281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:08.058843Z","time spent":"1.133945013s","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:09.192868Z","caller":"traceutil/trace.go:171","msg":"trace[1808765219] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.126610266s","start":"2026-07-25T01:11:08.066229Z","end":"2026-07-25T01:11:09.192839Z","steps":["trace[1808765219] 'process raft request' (duration: 1.126055783s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.192957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:08.066208Z","time spent":"1.12670245s","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:11:09.205147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283303676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:11:09.205185Z","caller":"traceutil/trace.go:171","msg":"trace[633331269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"1.283345377s","start":"2026-07-25T01:11:07.921831Z","end":"2026-07-25T01:11:09.205176Z","steps":["trace[633331269] 'agreement among raft nodes before linearized reading' (duration: 1.283215042s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.205204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:07.921762Z","time spent":"1.283438411s","remote":"127.0.0.1:56378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T01:11:09.202563Z","caller":"traceutil/trace.go:171","msg":"trace[17682571] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:521; }","duration":"1.152267927s","start":"2026-07-25T01:11:08.050255Z","end":"2026-07-25T01:11:09.202523Z","steps":["trace[17682571] 'read index received' (duration: 624.524344ms)","trace[17682571] 'applied index is now lower than readState.Index' (duration: 527.742903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:11:09.21556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.982218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T01:11:09.215636Z","caller":"traceutil/trace.go:171","msg":"trace[412806705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"159.109503ms","start":"2026-07-25T01:11:09.05651Z","end":"2026-07-25T01:11:09.215619Z","steps":["trace[412806705] 'agreement among raft nodes before linearized reading' (duration: 158.957116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.215708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.43133ms","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-07-25T01:11:09.215779Z","caller":"traceutil/trace.go:171","msg":"trace[726540212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"811.563685ms","start":"2026-07-25T01:11:08.404198Z","end":"2026-07-25T01:11:09.215762Z","steps":["trace[726540212] 'agreement among raft nodes before linearized reading' (duration: 811.361427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.215813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:08.404171Z","time spent":"811.636368ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T01:11:09.215942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.409175ms","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-07-25T01:11:09.215971Z","caller":"traceutil/trace.go:171","msg":"trace[390456607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"323.439457ms","start":"2026-07-25T01:11:08.892524Z","end":"2026-07-25T01:11:09.215964Z","steps":["trace[390456607] 'agreement among raft nodes before linearized reading' (duration: 323.367724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:09.215994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:08.892456Z","time spent":"323.530871ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T01:11:10.765439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.666812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:11:10.765586Z","caller":"traceutil/trace.go:171","msg":"trace[677121401] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"150.136101ms","start":"2026-07-25T01:11:10.615427Z","end":"2026-07-25T01:11:10.765563Z","steps":["trace[677121401] 'read index received' (duration: 59.512µs)","trace[677121401] 'applied index is now lower than readState.Index' (duration: 150.074859ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:11:10.765726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.291828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-07-25T01:11:10.76577Z","caller":"traceutil/trace.go:171","msg":"trace[1946457236] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:567; }","duration":"150.35923ms","start":"2026-07-25T01:11:10.615395Z","end":"2026-07-25T01:11:10.765754Z","steps":["trace[1946457236] 'agreement among raft nodes before linearized reading' (duration: 150.226175ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:10.765737Z","caller":"traceutil/trace.go:171","msg":"trace[415280965] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"197.206059ms","start":"2026-07-25T01:11:10.568478Z","end":"2026-07-25T01:11:10.765684Z","steps":["trace[415280965] 'process raft request' (duration: 30.159602ms)","trace[415280965] 'compare' (duration: 166.458473ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:11:15.831985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.766845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:11:15.832159Z","caller":"traceutil/trace.go:171","msg":"trace[783599804] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"552.751369ms","start":"2026-07-25T01:11:15.279356Z","end":"2026-07-25T01:11:15.832107Z","steps":["trace[783599804] 'process raft request' (duration: 389.750665ms)","trace[783599804] 'compare' (duration: 162.528374ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:11:15.832239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:15.279338Z","time spent":"552.865624ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:15.852607Z","caller":"traceutil/trace.go:171","msg":"trace[367841620] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"571.438661ms","start":"2026-07-25T01:11:15.281151Z","end":"2026-07-25T01:11:15.85259Z","steps":["trace[367841620] 'process raft request' (duration: 571.241523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:15.852701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:15.281116Z","time spent":"571.539565ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:15.853191Z","caller":"traceutil/trace.go:171","msg":"trace[544258725] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"571.918801ms","start":"2026-07-25T01:11:15.281253Z","end":"2026-07-25T01:11:15.853172Z","steps":["trace[544258725] 'process raft request' (duration: 571.276124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:15.853278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:15.281229Z","time spent":"572.010165ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:15.853472Z","caller":"traceutil/trace.go:171","msg":"trace[1617297332] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:608; }","duration":"566.906917ms","start":"2026-07-25T01:11:15.286552Z","end":"2026-07-25T01:11:15.853459Z","steps":["trace[1617297332] 'read index received' (duration: 382.679511ms)","trace[1617297332] 'applied index is now lower than readState.Index' (duration: 184.222605ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:11:15.853552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.99597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T01:11:15.853598Z","caller":"traceutil/trace.go:171","msg":"trace[159277423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"567.068953ms","start":"2026-07-25T01:11:15.286518Z","end":"2026-07-25T01:11:15.853587Z","steps":["trace[159277423] 'agreement among raft nodes before linearized reading' (duration: 566.9869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:15.85363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:15.286508Z","time spent":"567.115446ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T01:11:15.855461Z","caller":"traceutil/trace.go:171","msg":"trace[850916401] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"530.288465ms","start":"2026-07-25T01:11:15.325139Z","end":"2026-07-25T01:11:15.855427Z","steps":["trace[850916401] 'process raft request' (duration: 528.492128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:15.85563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:15.325112Z","time spent":"530.428011ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:11:16.759706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948391237,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T01:11:17.261308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948391237,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T01:11:17.728569Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.801947652s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-25T01:11:17.869801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.801085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:11:17.870056Z","caller":"traceutil/trace.go:171","msg":"trace[2016962953] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"1.610693314s","start":"2026-07-25T01:11:16.259339Z","end":"2026-07-25T01:11:17.870032Z","steps":["trace[2016962953] 'read index received' (duration: 1.469532403s)","trace[2016962953] 'applied index is now lower than readState.Index' (duration: 141.159521ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:11:17.870168Z","caller":"traceutil/trace.go:171","msg":"trace[426935902] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.943699938s","start":"2026-07-25T01:11:15.926455Z","end":"2026-07-25T01:11:17.870155Z","steps":["trace[426935902] 'process raft request' (duration: 1.802399942s)","trace[426935902] 'compare' (duration: 140.434548ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:11:17.870232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:15.926417Z","time spent":"1.943786242s","remote":"127.0.0.1:56560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T01:11:17.870658Z","caller":"traceutil/trace.go:171","msg":"trace[2117489986] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.912435126s","start":"2026-07-25T01:11:15.958185Z","end":"2026-07-25T01:11:17.87062Z","steps":["trace[2117489986] 'process raft request' (duration: 1.911761367s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:17.870751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.611377273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T01:11:17.870826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:15.958155Z","time spent":"1.912583324s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:17.870887Z","caller":"traceutil/trace.go:171","msg":"trace[1548294931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"1.611549471s","start":"2026-07-25T01:11:16.259305Z","end":"2026-07-25T01:11:17.870854Z","steps":["trace[1548294931] 'agreement among raft nodes before linearized reading' (duration: 1.611281729s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:17.870948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:16.259286Z","time spent":"1.611651965s","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T01:11:17.872379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.553121ms","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-07-25T01:11:17.872471Z","caller":"traceutil/trace.go:171","msg":"trace[516041957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"607.689467ms","start":"2026-07-25T01:11:17.264757Z","end":"2026-07-25T01:11:17.872446Z","steps":["trace[516041957] 'agreement among raft nodes before linearized reading' (duration: 605.802515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:17.872876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:17.264731Z","time spent":"608.128626ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T01:11:33.778196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.859596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-25T01:11:33.778314Z","caller":"traceutil/trace.go:171","msg":"trace[508350515] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:765; }","duration":"686.007874ms","start":"2026-07-25T01:11:33.092262Z","end":"2026-07-25T01:11:33.77827Z","steps":["trace[508350515] 'range keys from in-memory index tree' (duration: 685.590243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:33.77836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:33.092234Z","time spent":"686.116968ms","remote":"127.0.0.1:56400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-07-25T01:11:33.778673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.945787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:11:33.778773Z","caller":"traceutil/trace.go:171","msg":"trace[835399634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"520.121466ms","start":"2026-07-25T01:11:33.258629Z","end":"2026-07-25T01:11:33.77875Z","steps":["trace[835399634] 'range keys from in-memory index tree' (duration: 519.80576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:33.77883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:33.258592Z","time spent":"520.22928ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T01:11:33.882524Z","caller":"traceutil/trace.go:171","msg":"trace[1174092978] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"410.918177ms","start":"2026-07-25T01:11:33.471583Z","end":"2026-07-25T01:11:33.882501Z","steps":["trace[1174092978] 'read index received' (duration: 410.809421ms)","trace[1174092978] 'applied index is now lower than readState.Index' (duration: 107.626µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:11:33.882658Z","caller":"traceutil/trace.go:171","msg":"trace[1441361788] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"493.953595ms","start":"2026-07-25T01:11:33.388655Z","end":"2026-07-25T01:11:33.882608Z","steps":["trace[1441361788] 'process raft request' (duration: 493.6415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:33.882691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.082755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T01:11:33.882929Z","caller":"traceutil/trace.go:171","msg":"trace[299903111] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:766; }","duration":"411.308785ms","start":"2026-07-25T01:11:33.471538Z","end":"2026-07-25T01:11:33.882846Z","steps":["trace[299903111] 'agreement among raft nodes before linearized reading' (duration: 411.085005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:33.882991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:33.471502Z","time spent":"411.473674ms","remote":"127.0.0.1:56778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-25T01:11:33.883575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:33.388621Z","time spent":"494.121223ms","remote":"127.0.0.1:56608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:11:34.083241Z","caller":"traceutil/trace.go:171","msg":"trace[818520838] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"200.608501ms","start":"2026-07-25T01:11:33.882598Z","end":"2026-07-25T01:11:34.083207Z","steps":["trace[818520838] 'read index received' (duration: 198.233076ms)","trace[818520838] 'applied index is now lower than readState.Index' (duration: 2.373785ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:11:34.083242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:33.779985Z","time spent":"303.250854ms","remote":"127.0.0.1:56400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-25T01:11:34.083566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.608686ms","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-07-25T01:11:34.083647Z","caller":"traceutil/trace.go:171","msg":"trace[908910138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"100.752913ms","start":"2026-07-25T01:11:33.982876Z","end":"2026-07-25T01:11:34.083628Z","steps":["trace[908910138] 'agreement among raft nodes before linearized reading' (duration: 100.509001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:34.083946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.507091ms","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-07-25T01:11:34.083961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.576241ms","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-07-25T01:11:34.08409Z","caller":"traceutil/trace.go:171","msg":"trace[2028744919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"215.719628ms","start":"2026-07-25T01:11:33.868357Z","end":"2026-07-25T01:11:34.084076Z","steps":["trace[2028744919] 'agreement among raft nodes before linearized reading' (duration: 215.480367ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:11:34.084103Z","caller":"traceutil/trace.go:171","msg":"trace[1522703160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"100.748723ms","start":"2026-07-25T01:11:33.98332Z","end":"2026-07-25T01:11:34.084068Z","steps":["trace[1522703160] 'agreement among raft nodes before linearized reading' (duration: 100.28348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:34.084367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.356717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:11:34.084422Z","caller":"traceutil/trace.go:171","msg":"trace[563531475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"304.454421ms","start":"2026-07-25T01:11:33.779955Z","end":"2026-07-25T01:11:34.084409Z","steps":["trace[563531475] 'agreement among raft nodes before linearized reading' (duration: 303.38616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:11:34.084456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:11:33.779926Z","time spent":"304.522455ms","remote":"127.0.0.1:56378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T01:12:09.353656Z","caller":"traceutil/trace.go:171","msg":"trace[1469758723] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"209.022251ms","start":"2026-07-25T01:12:09.144608Z","end":"2026-07-25T01:12:09.35363Z","steps":["trace[1469758723] 'process raft request' (duration: 208.540375ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:12:31.727725Z","caller":"traceutil/trace.go:171","msg":"trace[66248358] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"320.393506ms","start":"2026-07-25T01:12:31.407308Z","end":"2026-07-25T01:12:31.727702Z","steps":["trace[66248358] 'process raft request' (duration: 320.260328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:12:31.727875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:12:31.407292Z","time spent":"320.506802ms","remote":"127.0.0.1:56838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2416,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:12:31.728644Z","caller":"traceutil/trace.go:171","msg":"trace[789688682] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"288.467822ms","start":"2026-07-25T01:12:31.440161Z","end":"2026-07-25T01:12:31.728629Z","steps":["trace[789688682] 'process raft request' (duration: 288.195527ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:12:34.538898Z","caller":"traceutil/trace.go:171","msg":"trace[1088147105] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"118.861007ms","start":"2026-07-25T01:12:34.420012Z","end":"2026-07-25T01:12:34.538873Z","steps":["trace[1088147105] 'process raft request' (duration: 118.723179ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:12:34.538951Z","caller":"traceutil/trace.go:171","msg":"trace[1071011125] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1506; }","duration":"113.904979ms","start":"2026-07-25T01:12:34.425021Z","end":"2026-07-25T01:12:34.538926Z","steps":["trace[1071011125] 'read index received' (duration: 113.897159ms)","trace[1071011125] 'applied index is now lower than readState.Index' (duration: 5.93µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:12:34.539182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.121351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T01:12:34.539208Z","caller":"traceutil/trace.go:171","msg":"trace[170677135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1460; }","duration":"114.204157ms","start":"2026-07-25T01:12:34.424994Z","end":"2026-07-25T01:12:34.539198Z","steps":["trace[170677135] 'agreement among raft nodes before linearized reading' (duration: 114.056938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:12:34.539192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.149443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-25T01:12:34.539194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.342716ms","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-07-25T01:12:34.539264Z","caller":"traceutil/trace.go:171","msg":"trace[1937270071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1460; }","duration":"114.290532ms","start":"2026-07-25T01:12:34.424954Z","end":"2026-07-25T01:12:34.539245Z","steps":["trace[1937270071] 'agreement among raft nodes before linearized reading' (duration: 114.069309ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:12:34.539272Z","caller":"traceutil/trace.go:171","msg":"trace[280888353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1460; }","duration":"103.451392ms","start":"2026-07-25T01:12:34.435804Z","end":"2026-07-25T01:12:34.539255Z","steps":["trace[280888353] 'agreement among raft nodes before linearized reading' (duration: 103.24432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:12:34.539353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.580949ms","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-07-25T01:12:34.539394Z","caller":"traceutil/trace.go:171","msg":"trace[1402348583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1460; }","duration":"109.660383ms","start":"2026-07-25T01:12:34.429721Z","end":"2026-07-25T01:12:34.539381Z","steps":["trace[1402348583] 'agreement among raft nodes before linearized reading' (duration: 109.526075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:12:58.573676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948393743,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T01:12:58.614602Z","caller":"traceutil/trace.go:171","msg":"trace[1218726445] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"541.238818ms","start":"2026-07-25T01:12:58.073337Z","end":"2026-07-25T01:12:58.614576Z","steps":["trace[1218726445] 'read index received' (duration: 541.080928ms)","trace[1218726445] 'applied index is now lower than readState.Index' (duration: 157.07µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:12:58.614868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.509744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-79c9e954-2f9c-4249-a8c0-52e1dbeacd63\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-25T01:12:58.614952Z","caller":"traceutil/trace.go:171","msg":"trace[985035727] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-79c9e954-2f9c-4249-a8c0-52e1dbeacd63; range_end:; response_count:1; response_revision:1866; }","duration":"541.651842ms","start":"2026-07-25T01:12:58.073283Z","end":"2026-07-25T01:12:58.614934Z","steps":["trace[985035727] 'agreement among raft nodes before linearized reading' (duration: 541.406107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:12:58.615004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:12:58.073262Z","time spent":"541.733028ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-79c9e954-2f9c-4249-a8c0-52e1dbeacd63\" "} {"level":"info","ts":"2026-07-25T01:12:58.615419Z","caller":"traceutil/trace.go:171","msg":"trace[291853490] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"637.284773ms","start":"2026-07-25T01:12:57.9781Z","end":"2026-07-25T01:12:58.615385Z","steps":["trace[291853490] 'process raft request' (duration: 636.328496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:12:58.615738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:12:57.978076Z","time spent":"637.497996ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:12:58.763796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.347384ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-25T01:12:58.763858Z","caller":"traceutil/trace.go:171","msg":"trace[1738900904] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"149.181034ms","start":"2026-07-25T01:12:58.614663Z","end":"2026-07-25T01:12:58.763844Z","steps":["trace[1738900904] 'read index received' (duration: 18.68147ms)","trace[1738900904] 'applied index is now lower than readState.Index' (duration: 130.498493ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:12:58.764021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.765584ms","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-07-25T01:12:58.764046Z","caller":"traceutil/trace.go:171","msg":"trace[433401704] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1866; }","duration":"639.833978ms","start":"2026-07-25T01:12:58.124205Z","end":"2026-07-25T01:12:58.764039Z","steps":["trace[433401704] 'agreement among raft nodes before linearized reading' (duration: 639.69556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:12:58.764071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:12:58.12418Z","time spent":"639.883622ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T01:12:58.764057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.851866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:12:58.764163Z","caller":"traceutil/trace.go:171","msg":"trace[15985748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"505.054619ms","start":"2026-07-25T01:12:58.25909Z","end":"2026-07-25T01:12:58.764145Z","steps":["trace[15985748] 'agreement among raft nodes before linearized reading' (duration: 504.809684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:12:58.764237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:12:58.259062Z","time spent":"505.164745ms","remote":"127.0.0.1:56378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T01:12:58.764262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.656894ms","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-07-25T01:12:58.76434Z","caller":"traceutil/trace.go:171","msg":"trace[1485011255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1866; }","duration":"110.788862ms","start":"2026-07-25T01:12:58.653534Z","end":"2026-07-25T01:12:58.764322Z","steps":["trace[1485011255] 'agreement among raft nodes before linearized reading' (duration: 110.528347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:12:58.76453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.667466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:12:58.764677Z","caller":"traceutil/trace.go:171","msg":"trace[2061368225] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1866; }","duration":"281.850628ms","start":"2026-07-25T01:12:58.482811Z","end":"2026-07-25T01:12:58.764661Z","steps":["trace[2061368225] 'agreement among raft nodes before linearized reading' (duration: 281.669967ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:12:59.089387Z","caller":"traceutil/trace.go:171","msg":"trace[243897750] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"191.310405ms","start":"2026-07-25T01:12:58.89805Z","end":"2026-07-25T01:12:59.089361Z","steps":["trace[243897750] 'process raft request' (duration: 191.142795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:07.765356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.763459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T01:13:07.765457Z","caller":"traceutil/trace.go:171","msg":"trace[51767510] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1948; }","duration":"103.946169ms","start":"2026-07-25T01:13:07.661487Z","end":"2026-07-25T01:13:07.765433Z","steps":["trace[51767510] 'count revisions from in-memory index tree' (duration: 103.649781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:07.765458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.219885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T01:13:07.765558Z","caller":"traceutil/trace.go:171","msg":"trace[1803761859] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1948; }","duration":"181.380684ms","start":"2026-07-25T01:13:07.584153Z","end":"2026-07-25T01:13:07.765533Z","steps":["trace[1803761859] 'count revisions from in-memory index tree' (duration: 181.14215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:11.366491Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948393968,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T01:13:11.867232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948393968,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T01:13:11.892631Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.031726365s","expected-duration":"1s"} {"level":"info","ts":"2026-07-25T01:13:11.893631Z","caller":"traceutil/trace.go:171","msg":"trace[1845457221] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"1.032834583s","start":"2026-07-25T01:13:10.860771Z","end":"2026-07-25T01:13:11.893605Z","steps":["trace[1845457221] 'process raft request' (duration: 1.032206835s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:11.893783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:10.860746Z","time spent":"1.032952819s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:11.895879Z","caller":"traceutil/trace.go:171","msg":"trace[2037614932] linearizableReadLoop","detail":"{readStateIndex:2048; appliedIndex:2044; }","duration":"1.03017007s","start":"2026-07-25T01:13:10.865655Z","end":"2026-07-25T01:13:11.895825Z","steps":["trace[2037614932] 'read index received' (duration: 1.027379221s)","trace[2037614932] 'applied index is now lower than readState.Index' (duration: 2.789729ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:13:11.896335Z","caller":"traceutil/trace.go:171","msg":"trace[6658351] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"762.12521ms","start":"2026-07-25T01:13:11.134176Z","end":"2026-07-25T01:13:11.896301Z","steps":["trace[6658351] 'process raft request' (duration: 761.120099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:11.896521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:11.134146Z","time spent":"762.266029ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:11.896598Z","caller":"traceutil/trace.go:171","msg":"trace[1704392084] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"470.499921ms","start":"2026-07-25T01:13:11.426056Z","end":"2026-07-25T01:13:11.896556Z","steps":["trace[1704392084] 'process raft request' (duration: 469.557674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:11.896733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031059535s","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-07-25T01:13:11.896798Z","caller":"traceutil/trace.go:171","msg":"trace[2138250803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1972; }","duration":"1.031214184s","start":"2026-07-25T01:13:10.865564Z","end":"2026-07-25T01:13:11.896778Z","steps":["trace[2138250803] 'agreement among raft nodes before linearized reading' (duration: 1.030938948s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:11.896855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:10.865542Z","time spent":"1.031303649s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T01:13:11.896902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031163221s","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-07-25T01:13:11.896936Z","caller":"traceutil/trace.go:171","msg":"trace[561856561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1972; }","duration":"1.031228875s","start":"2026-07-25T01:13:10.865698Z","end":"2026-07-25T01:13:11.896927Z","steps":["trace[561856561] 'agreement among raft nodes before linearized reading' (duration: 1.031106618s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:11.896964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:10.865677Z","time spent":"1.031282848s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T01:13:11.897082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023820506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T01:13:11.897104Z","caller":"traceutil/trace.go:171","msg":"trace[221971860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1972; }","duration":"1.023855748s","start":"2026-07-25T01:13:10.873243Z","end":"2026-07-25T01:13:11.897099Z","steps":["trace[221971860] 'agreement among raft nodes before linearized reading' (duration: 1.023799214s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:11.897125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:10.873231Z","time spent":"1.02388962s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T01:13:11.897267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029043653s","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-07-25T01:13:11.89729Z","caller":"traceutil/trace.go:171","msg":"trace[471956740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1972; }","duration":"1.029094515s","start":"2026-07-25T01:13:10.86819Z","end":"2026-07-25T01:13:11.897285Z","steps":["trace[471956740] 'agreement among raft nodes before linearized reading' (duration: 1.029036802s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:11.897308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:10.868176Z","time spent":"1.029128507s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T01:13:11.89693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.925757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:13:11.897387Z","caller":"traceutil/trace.go:171","msg":"trace[2037838106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1972; }","duration":"637.434978ms","start":"2026-07-25T01:13:11.259944Z","end":"2026-07-25T01:13:11.897379Z","steps":["trace[2037838106] 'agreement among raft nodes before linearized reading' (duration: 636.931197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:11.897413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:11.259914Z","time spent":"637.493232ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T01:13:11.896743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:11.426031Z","time spent":"470.63733ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:28.193136Z","caller":"traceutil/trace.go:171","msg":"trace[1203163465] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"217.356644ms","start":"2026-07-25T01:13:27.975755Z","end":"2026-07-25T01:13:28.193112Z","steps":["trace[1203163465] 'process raft request' (duration: 217.184633ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:28.193357Z","caller":"traceutil/trace.go:171","msg":"trace[159070565] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2154; }","duration":"181.021208ms","start":"2026-07-25T01:13:28.012278Z","end":"2026-07-25T01:13:28.193299Z","steps":["trace[159070565] 'read index received' (duration: 180.717569ms)","trace[159070565] 'applied index is now lower than readState.Index' (duration: 302.589µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:13:28.193458Z","caller":"traceutil/trace.go:171","msg":"trace[1534334845] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"209.588605ms","start":"2026-07-25T01:13:27.983807Z","end":"2026-07-25T01:13:28.193396Z","steps":["trace[1534334845] 'process raft request' (duration: 209.266615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:28.193605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.292715ms","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-07-25T01:13:28.193793Z","caller":"traceutil/trace.go:171","msg":"trace[42811453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2076; }","duration":"181.544881ms","start":"2026-07-25T01:13:28.012238Z","end":"2026-07-25T01:13:28.193783Z","steps":["trace[42811453] 'agreement among raft nodes before linearized reading' (duration: 181.20421ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:28.293449Z","caller":"traceutil/trace.go:171","msg":"trace[98785777] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2156; }","duration":"100.026044ms","start":"2026-07-25T01:13:28.19339Z","end":"2026-07-25T01:13:28.293416Z","steps":["trace[98785777] 'read index received' (duration: 55.482473ms)","trace[98785777] 'applied index is now lower than readState.Index' (duration: 44.539281ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:13:28.29377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.404401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-25T01:13:28.293765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.867776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-25T01:13:28.293826Z","caller":"traceutil/trace.go:171","msg":"trace[1167569112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2077; }","duration":"257.956921ms","start":"2026-07-25T01:13:28.035854Z","end":"2026-07-25T01:13:28.293811Z","steps":["trace[1167569112] 'agreement among raft nodes before linearized reading' (duration: 257.717567ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:28.293856Z","caller":"traceutil/trace.go:171","msg":"trace[124079749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2077; }","duration":"259.537129ms","start":"2026-07-25T01:13:28.034304Z","end":"2026-07-25T01:13:28.293841Z","steps":["trace[124079749] 'agreement among raft nodes before linearized reading' (duration: 259.294014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:28.293778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.194292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T01:13:28.29392Z","caller":"traceutil/trace.go:171","msg":"trace[458372067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2077; }","duration":"255.369843ms","start":"2026-07-25T01:13:28.038532Z","end":"2026-07-25T01:13:28.293902Z","steps":["trace[458372067] 'agreement among raft nodes before linearized reading' (duration: 255.060464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:28.29402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.397915ms","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-07-25T01:13:28.294103Z","caller":"traceutil/trace.go:171","msg":"trace[874795690] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2077; }","duration":"268.518022ms","start":"2026-07-25T01:13:28.025564Z","end":"2026-07-25T01:13:28.294082Z","steps":["trace[874795690] 'agreement among raft nodes before linearized reading' (duration: 268.295358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:28.294264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.768869ms","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-07-25T01:13:28.294498Z","caller":"traceutil/trace.go:171","msg":"trace[1619972665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2077; }","duration":"256.879255ms","start":"2026-07-25T01:13:28.037453Z","end":"2026-07-25T01:13:28.294332Z","steps":["trace[1619972665] 'agreement among raft nodes before linearized reading' (duration: 256.190813ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:34.49463Z","caller":"traceutil/trace.go:171","msg":"trace[1437257278] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"277.061242ms","start":"2026-07-25T01:13:34.21752Z","end":"2026-07-25T01:13:34.494581Z","steps":["trace[1437257278] 'process raft request' (duration: 276.749943ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:34.859742Z","caller":"traceutil/trace.go:171","msg":"trace[767795644] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2190; }","duration":"539.62987ms","start":"2026-07-25T01:13:34.320068Z","end":"2026-07-25T01:13:34.859698Z","steps":["trace[767795644] 'read index received' (duration: 174.145165ms)","trace[767795644] 'applied index is now lower than readState.Index' (duration: 365.483275ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:13:34.859818Z","caller":"traceutil/trace.go:171","msg":"trace[1064699740] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"641.410647ms","start":"2026-07-25T01:13:34.218343Z","end":"2026-07-25T01:13:34.859754Z","steps":["trace[1064699740] 'process raft request' (duration: 553.821227ms)","trace[1064699740] 'compare' (duration: 87.367997ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:13:34.860036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.218323Z","time spent":"641.594588ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:13:34.860153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.03425ms","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-07-25T01:13:34.860247Z","caller":"traceutil/trace.go:171","msg":"trace[2137276757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2110; }","duration":"509.171519ms","start":"2026-07-25T01:13:34.351049Z","end":"2026-07-25T01:13:34.860221Z","steps":["trace[2137276757] 'agreement among raft nodes before linearized reading' (duration: 508.899132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:34.860261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.309918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T01:13:34.860368Z","caller":"traceutil/trace.go:171","msg":"trace[2008023841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2110; }","duration":"509.447096ms","start":"2026-07-25T01:13:34.350896Z","end":"2026-07-25T01:13:34.860343Z","steps":["trace[2008023841] 'agreement among raft nodes before linearized reading' (duration: 509.083243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:34.86039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.192085ms","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-07-25T01:13:34.860425Z","caller":"traceutil/trace.go:171","msg":"trace[258344533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2110; }","duration":"506.232697ms","start":"2026-07-25T01:13:34.354183Z","end":"2026-07-25T01:13:34.860416Z","steps":["trace[258344533] 'agreement among raft nodes before linearized reading' (duration: 506.017204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:34.860436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.350883Z","time spent":"509.538242ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T01:13:34.860513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.023419ms","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-07-25T01:13:34.860544Z","caller":"traceutil/trace.go:171","msg":"trace[773372817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2110; }","duration":"525.071662ms","start":"2026-07-25T01:13:34.335465Z","end":"2026-07-25T01:13:34.860537Z","steps":["trace[773372817] 'agreement among raft nodes before linearized reading' (duration: 524.470034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:34.860297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.351042Z","time spent":"509.244693ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T01:13:34.860568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.335456Z","time spent":"525.107033ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T01:13:34.860774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.346672ms","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-07-25T01:13:34.860823Z","caller":"traceutil/trace.go:171","msg":"trace[1101908965] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2110; }","duration":"516.383574ms","start":"2026-07-25T01:13:34.344419Z","end":"2026-07-25T01:13:34.860802Z","steps":["trace[1101908965] 'agreement among raft nodes before linearized reading' (duration: 515.604686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:34.860451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.354177Z","time spent":"506.26748ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T01:13:34.860852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.344326Z","time spent":"516.519313ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T01:13:34.861228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.148224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T01:13:34.861281Z","caller":"traceutil/trace.go:171","msg":"trace[110360038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2110; }","duration":"541.23787ms","start":"2026-07-25T01:13:34.320031Z","end":"2026-07-25T01:13:34.861269Z","steps":["trace[110360038] 'agreement among raft nodes before linearized reading' (duration: 539.78993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:34.86131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.320019Z","time spent":"541.284652ms","remote":"127.0.0.1:56632","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-07-25T01:13:35.702053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.992752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:13:35.702326Z","caller":"traceutil/trace.go:171","msg":"trace[689952205] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"835.758396ms","start":"2026-07-25T01:13:34.866512Z","end":"2026-07-25T01:13:35.702271Z","steps":["trace[689952205] 'process raft request' (duration: 106.433323ms)","trace[689952205] 'compare' (duration: 728.846013ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:13:35.70247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.403412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:13:35.702513Z","caller":"traceutil/trace.go:171","msg":"trace[343820000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2116; }","duration":"442.500017ms","start":"2026-07-25T01:13:35.260001Z","end":"2026-07-25T01:13:35.702501Z","steps":["trace[343820000] 'agreement among raft nodes before linearized reading' (duration: 442.406241ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:35.702508Z","caller":"traceutil/trace.go:171","msg":"trace[1043217375] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"834.796837ms","start":"2026-07-25T01:13:34.867667Z","end":"2026-07-25T01:13:35.702464Z","steps":["trace[1043217375] 'process raft request' (duration: 834.565292ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:35.702597Z","caller":"traceutil/trace.go:171","msg":"trace[1608925230] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"834.670999ms","start":"2026-07-25T01:13:34.867918Z","end":"2026-07-25T01:13:35.702589Z","steps":["trace[1608925230] 'process raft request' (duration: 834.35396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:35.702672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.867909Z","time spent":"834.719322ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:13:35.702734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.867658Z","time spent":"834.942586ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:35.70282Z","caller":"traceutil/trace.go:171","msg":"trace[1687241559] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"836.246076ms","start":"2026-07-25T01:13:34.86656Z","end":"2026-07-25T01:13:35.702806Z","steps":["trace[1687241559] 'process raft request' (duration: 835.609037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:35.702902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.866526Z","time spent":"836.337582ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:13:35.702537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.866497Z","time spent":"835.928646ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:35.70237Z","caller":"traceutil/trace.go:171","msg":"trace[1672545998] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2193; }","duration":"442.268883ms","start":"2026-07-25T01:13:35.260079Z","end":"2026-07-25T01:13:35.702347Z","steps":["trace[1672545998] 'read index received' (duration: 158.739µs)","trace[1672545998] 'applied index is now lower than readState.Index' (duration: 442.109384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:13:35.702553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:35.259977Z","time spent":"442.568482ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T01:13:35.702899Z","caller":"traceutil/trace.go:171","msg":"trace[244097615] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"834.493758ms","start":"2026-07-25T01:13:34.868394Z","end":"2026-07-25T01:13:35.702888Z","steps":["trace[244097615] 'process raft request' (duration: 833.910972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:35.704088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:34.868387Z","time spent":"835.65922ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:13:35.957691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.405915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:13:35.957779Z","caller":"traceutil/trace.go:171","msg":"trace[98794897] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2116; }","duration":"150.561005ms","start":"2026-07-25T01:13:35.807195Z","end":"2026-07-25T01:13:35.957756Z","steps":["trace[98794897] 'count revisions from in-memory index tree' (duration: 150.286498ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:36.671294Z","caller":"traceutil/trace.go:171","msg":"trace[1480317457] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2198; }","duration":"410.9154ms","start":"2026-07-25T01:13:36.260355Z","end":"2026-07-25T01:13:36.67127Z","steps":["trace[1480317457] 'read index received' (duration: 410.74566ms)","trace[1480317457] 'applied index is now lower than readState.Index' (duration: 168.74µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:13:36.671389Z","caller":"traceutil/trace.go:171","msg":"trace[213637293] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"565.198611ms","start":"2026-07-25T01:13:36.106151Z","end":"2026-07-25T01:13:36.67135Z","steps":["trace[213637293] 'process raft request' (duration: 564.970797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:36.671557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.191298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T01:13:36.671603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:36.106125Z","time spent":"565.358992ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:36.671636Z","caller":"traceutil/trace.go:171","msg":"trace[457585444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2117; }","duration":"411.342336ms","start":"2026-07-25T01:13:36.260283Z","end":"2026-07-25T01:13:36.671625Z","steps":["trace[457585444] 'agreement among raft nodes before linearized reading' (duration: 411.220899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:36.671756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:36.260256Z","time spent":"411.492926ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T01:13:37.74034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.870469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:13:37.740598Z","caller":"traceutil/trace.go:171","msg":"trace[1232232983] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"874.107221ms","start":"2026-07-25T01:13:36.866466Z","end":"2026-07-25T01:13:37.740573Z","steps":["trace[1232232983] 'process raft request' (duration: 874.015925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.740699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:36.866439Z","time spent":"874.209717ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:37.740974Z","caller":"traceutil/trace.go:171","msg":"trace[1986307039] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"1.240246461s","start":"2026-07-25T01:13:36.500708Z","end":"2026-07-25T01:13:37.740954Z","steps":["trace[1986307039] 'process raft request' (duration: 287.623916ms)","trace[1986307039] 'compare' (duration: 951.695808ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:13:37.741062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:36.500685Z","time spent":"1.240330246s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:37.741252Z","caller":"traceutil/trace.go:171","msg":"trace[926499814] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2199; }","duration":"1.06978528s","start":"2026-07-25T01:13:36.671451Z","end":"2026-07-25T01:13:37.741236Z","steps":["trace[926499814] 'read index received' (duration: 116.825803ms)","trace[926499814] 'applied index is now lower than readState.Index' (duration: 952.957717ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:13:37.741554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260517004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:13:37.741626Z","caller":"traceutil/trace.go:171","msg":"trace[190662921] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2119; }","duration":"1.26060291s","start":"2026-07-25T01:13:36.481004Z","end":"2026-07-25T01:13:37.741607Z","steps":["trace[190662921] 'agreement among raft nodes before linearized reading' (duration: 1.260476812s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.741624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.47777525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c5634bb2188dad\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-07-25T01:13:37.741676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:36.480936Z","time spent":"1.260730458s","remote":"127.0.0.1:56572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-07-25T01:13:37.741698Z","caller":"traceutil/trace.go:171","msg":"trace[2118187758] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c5634bb2188dad; range_end:; response_count:1; response_revision:2119; }","duration":"1.477873486s","start":"2026-07-25T01:13:36.263803Z","end":"2026-07-25T01:13:37.741676Z","steps":["trace[2118187758] 'agreement among raft nodes before linearized reading' (duration: 1.477655782s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.741741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:36.263793Z","time spent":"1.47793933s","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c5634bb2188dad\" "} {"level":"info","ts":"2026-07-25T01:13:37.825051Z","caller":"traceutil/trace.go:171","msg":"trace[536277161] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"578.020425ms","start":"2026-07-25T01:13:37.247Z","end":"2026-07-25T01:13:37.82502Z","steps":["trace[536277161] 'process raft request' (duration: 577.808832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.825191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.254141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T01:13:37.825219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.871059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-25T01:13:37.825222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.246974Z","time spent":"578.153283ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:37.825239Z","caller":"traceutil/trace.go:171","msg":"trace[202110299] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2120; }","duration":"541.324315ms","start":"2026-07-25T01:13:37.2839Z","end":"2026-07-25T01:13:37.825225Z","steps":["trace[202110299] 'agreement among raft nodes before linearized reading' (duration: 541.24919ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:37.825266Z","caller":"traceutil/trace.go:171","msg":"trace[2137475407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2120; }","duration":"938.989897ms","start":"2026-07-25T01:13:36.886265Z","end":"2026-07-25T01:13:37.825255Z","steps":["trace[2137475407] 'agreement among raft nodes before linearized reading' (duration: 938.837717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.825275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.28389Z","time spent":"541.375018ms","remote":"127.0.0.1:44406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-07-25T01:13:37.825295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:36.886234Z","time spent":"939.05606ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T01:13:37.825446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.383312ms","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-07-25T01:13:37.825488Z","caller":"traceutil/trace.go:171","msg":"trace[1060187750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2120; }","duration":"116.442595ms","start":"2026-07-25T01:13:37.709035Z","end":"2026-07-25T01:13:37.825477Z","steps":["trace[1060187750] 'agreement among raft nodes before linearized reading' (duration: 116.328508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.825513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.316299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T01:13:37.825527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.852271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T01:13:37.825568Z","caller":"traceutil/trace.go:171","msg":"trace[1604853174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2120; }","duration":"116.947756ms","start":"2026-07-25T01:13:37.70861Z","end":"2026-07-25T01:13:37.825558Z","steps":["trace[1604853174] 'agreement among raft nodes before linearized reading' (duration: 116.813818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.825639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.830302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-07-25T01:13:37.825692Z","caller":"traceutil/trace.go:171","msg":"trace[783684912] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2120; }","duration":"656.907936ms","start":"2026-07-25T01:13:37.168776Z","end":"2026-07-25T01:13:37.825684Z","steps":["trace[783684912] 'agreement among raft nodes before linearized reading' (duration: 656.772948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.82571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.778748ms","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-07-25T01:13:37.825743Z","caller":"traceutil/trace.go:171","msg":"trace[1027767009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2120; }","duration":"117.884264ms","start":"2026-07-25T01:13:37.707851Z","end":"2026-07-25T01:13:37.825735Z","steps":["trace[1027767009] 'agreement among raft nodes before linearized reading' (duration: 117.794839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.825744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.889751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-25T01:13:37.825832Z","caller":"traceutil/trace.go:171","msg":"trace[547633118] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2120; }","duration":"116.009669ms","start":"2026-07-25T01:13:37.709798Z","end":"2026-07-25T01:13:37.825808Z","steps":["trace[547633118] 'agreement among raft nodes before linearized reading' (duration: 115.402581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.825719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.168758Z","time spent":"656.950989ms","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2013,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-07-25T01:13:37.825536Z","caller":"traceutil/trace.go:171","msg":"trace[1793283134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2120; }","duration":"565.371083ms","start":"2026-07-25T01:13:37.260157Z","end":"2026-07-25T01:13:37.825529Z","steps":["trace[1793283134] 'agreement among raft nodes before linearized reading' (duration: 565.33431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.826033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.260145Z","time spent":"565.872124ms","remote":"127.0.0.1:56378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T01:13:37.826227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.772587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"warn","ts":"2026-07-25T01:13:37.826177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.255556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T01:13:37.826259Z","caller":"traceutil/trace.go:171","msg":"trace[2145446409] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2120; }","duration":"789.854852ms","start":"2026-07-25T01:13:37.036396Z","end":"2026-07-25T01:13:37.826251Z","steps":["trace[2145446409] 'agreement among raft nodes before linearized reading' (duration: 789.741635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.826281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.036384Z","time spent":"789.891145ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12405,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-25T01:13:37.826285Z","caller":"traceutil/trace.go:171","msg":"trace[1081127795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2120; }","duration":"117.573375ms","start":"2026-07-25T01:13:37.708693Z","end":"2026-07-25T01:13:37.826266Z","steps":["trace[1081127795] 'agreement among raft nodes before linearized reading' (duration: 116.486848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.826296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.24033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:13:37.826334Z","caller":"traceutil/trace.go:171","msg":"trace[78242285] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2120; }","duration":"846.298855ms","start":"2026-07-25T01:13:36.980021Z","end":"2026-07-25T01:13:37.82632Z","steps":["trace[78242285] 'agreement among raft nodes before linearized reading' (duration: 846.241521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.826373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:36.980011Z","time spent":"846.353117ms","remote":"127.0.0.1:54972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-25T01:13:37.826191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.930547ms","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-07-25T01:13:37.82677Z","caller":"traceutil/trace.go:171","msg":"trace[1726237797] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2120; }","duration":"860.499753ms","start":"2026-07-25T01:13:36.966163Z","end":"2026-07-25T01:13:37.826663Z","steps":["trace[1726237797] 'agreement among raft nodes before linearized reading' (duration: 859.473859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:37.826859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:36.966129Z","time spent":"860.716456ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T01:13:38.338791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948394320,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T01:13:38.839249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948394320,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T01:13:39.042185Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.217452557s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-25T01:13:39.143364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.512056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:13:39.143479Z","caller":"traceutil/trace.go:171","msg":"trace[1031989627] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"1.398899032s","start":"2026-07-25T01:13:37.744557Z","end":"2026-07-25T01:13:39.143456Z","steps":["trace[1031989627] 'process raft request' (duration: 1.298178323s)","trace[1031989627] 'compare' (duration: 100.392318ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:13:39.143551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.744534Z","time spent":"1.398985957s","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:39.28627Z","caller":"traceutil/trace.go:171","msg":"trace[985106644] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"1.45398883s","start":"2026-07-25T01:13:37.832254Z","end":"2026-07-25T01:13:39.286243Z","steps":["trace[985106644] 'process raft request' (duration: 1.453779027s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:39.286449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.832228Z","time spent":"1.454117218s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:39.286832Z","caller":"traceutil/trace.go:171","msg":"trace[639431738] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"1.454335362s","start":"2026-07-25T01:13:37.832471Z","end":"2026-07-25T01:13:39.286806Z","steps":["trace[639431738] 'process raft request' (duration: 1.454199803s)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:39.28688Z","caller":"traceutil/trace.go:171","msg":"trace[786646236] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"1.454500492s","start":"2026-07-25T01:13:37.832346Z","end":"2026-07-25T01:13:39.286847Z","steps":["trace[786646236] 'process raft request' (duration: 1.453851222s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:39.287004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.83244Z","time spent":"1.45447495s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:13:39.287083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.832337Z","time spent":"1.45464139s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:39.287238Z","caller":"traceutil/trace.go:171","msg":"trace[1150984989] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"1.454884766s","start":"2026-07-25T01:13:37.832342Z","end":"2026-07-25T01:13:39.287227Z","steps":["trace[1150984989] 'process raft request' (duration: 1.454286529s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:39.2873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.832322Z","time spent":"1.454946229s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:39.28736Z","caller":"traceutil/trace.go:171","msg":"trace[1028304021] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"1.453441527s","start":"2026-07-25T01:13:37.833881Z","end":"2026-07-25T01:13:39.287322Z","steps":["trace[1028304021] 'process raft request' (duration: 1.452886262s)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:39.287418Z","caller":"traceutil/trace.go:171","msg":"trace[265733561] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"1.455065607s","start":"2026-07-25T01:13:37.832336Z","end":"2026-07-25T01:13:39.287401Z","steps":["trace[265733561] 'process raft request' (duration: 1.454164031s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:39.287479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.832317Z","time spent":"1.455126471s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:13:39.287561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.833867Z","time spent":"1.453573645s","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:39.294283Z","caller":"traceutil/trace.go:171","msg":"trace[1000822629] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"616.476356ms","start":"2026-07-25T01:13:38.677792Z","end":"2026-07-25T01:13:39.294269Z","steps":["trace[1000822629] 'process raft request' (duration: 616.353698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:39.294373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:38.677753Z","time spent":"616.568432ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:39.294383Z","caller":"traceutil/trace.go:171","msg":"trace[2070960227] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2202; }","duration":"1.455703107s","start":"2026-07-25T01:13:37.838664Z","end":"2026-07-25T01:13:39.294368Z","steps":["trace[2070960227] 'read index received' (duration: 1.204067899s)","trace[2070960227] 'applied index is now lower than readState.Index' (duration: 251.634138ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:13:39.294482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455823965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:13:39.294467Z","caller":"traceutil/trace.go:171","msg":"trace[188927850] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"534.675422ms","start":"2026-07-25T01:13:38.759762Z","end":"2026-07-25T01:13:39.294438Z","steps":["trace[188927850] 'process raft request' (duration: 534.460149ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:39.29451Z","caller":"traceutil/trace.go:171","msg":"trace[1403391165] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2129; }","duration":"1.455862176s","start":"2026-07-25T01:13:37.838638Z","end":"2026-07-25T01:13:39.2945Z","steps":["trace[1403391165] 'agreement among raft nodes before linearized reading' (duration: 1.455813693s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:39.294516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035286045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T01:13:39.294532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:37.83863Z","time spent":"1.455898029s","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-25T01:13:39.294531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.84233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T01:13:39.294555Z","caller":"traceutil/trace.go:171","msg":"trace[973191669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2129; }","duration":"1.03536744s","start":"2026-07-25T01:13:38.259177Z","end":"2026-07-25T01:13:39.294545Z","steps":["trace[973191669] 'agreement among raft nodes before linearized reading' (duration: 1.035307136s)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:39.294562Z","caller":"traceutil/trace.go:171","msg":"trace[554191591] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2129; }","duration":"148.878802ms","start":"2026-07-25T01:13:39.145675Z","end":"2026-07-25T01:13:39.294554Z","steps":["trace[554191591] 'agreement among raft nodes before linearized reading' (duration: 148.808218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:39.294578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:38.259154Z","time spent":"1.035420363s","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T01:13:39.294586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.616514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T01:13:39.294604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:38.75973Z","time spent":"534.79661ms","remote":"127.0.0.1:56536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:13:39.294623Z","caller":"traceutil/trace.go:171","msg":"trace[1270746397] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2129; }","duration":"338.700089ms","start":"2026-07-25T01:13:38.955912Z","end":"2026-07-25T01:13:39.294612Z","steps":["trace[1270746397] 'agreement among raft nodes before linearized reading' (duration: 338.637825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:39.294649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:38.955878Z","time spent":"338.766133ms","remote":"127.0.0.1:45134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-07-25T01:13:39.294722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.713307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-25T01:13:39.294777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.370006ms","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-07-25T01:13:39.294774Z","caller":"traceutil/trace.go:171","msg":"trace[167179859] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2129; }","duration":"130.810673ms","start":"2026-07-25T01:13:39.163946Z","end":"2026-07-25T01:13:39.294757Z","steps":["trace[167179859] 'agreement among raft nodes before linearized reading' (duration: 130.712947ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:13:39.294798Z","caller":"traceutil/trace.go:171","msg":"trace[1702111791] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2129; }","duration":"457.389567ms","start":"2026-07-25T01:13:38.837401Z","end":"2026-07-25T01:13:39.29479Z","steps":["trace[1702111791] 'agreement among raft nodes before linearized reading' (duration: 457.293731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:13:39.294818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:13:38.837325Z","time spent":"457.489554ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-25T01:14:33.592393Z","caller":"traceutil/trace.go:171","msg":"trace[257808342] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"204.286685ms","start":"2026-07-25T01:14:33.388087Z","end":"2026-07-25T01:14:33.592374Z","steps":["trace[257808342] 'process raft request' (duration: 204.150536ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:14:33.596032Z","caller":"traceutil/trace.go:171","msg":"trace[290463277] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"100.196971ms","start":"2026-07-25T01:14:33.495798Z","end":"2026-07-25T01:14:33.595995Z","steps":["trace[290463277] 'process raft request' (duration: 99.857202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:15.63575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.420987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-25T01:15:15.635831Z","caller":"traceutil/trace.go:171","msg":"trace[2095348141] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2748; }","duration":"461.544628ms","start":"2026-07-25T01:15:15.174264Z","end":"2026-07-25T01:15:15.635809Z","steps":["trace[2095348141] 'range keys from in-memory index tree' (duration: 461.297605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:15.635867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:15.174245Z","time spent":"461.614649ms","remote":"127.0.0.1:56530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-25T01:15:15.636206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.253451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:15:15.636234Z","caller":"traceutil/trace.go:171","msg":"trace[1776955738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2748; }","duration":"376.308272ms","start":"2026-07-25T01:15:15.25992Z","end":"2026-07-25T01:15:15.636228Z","steps":["trace[1776955738] 'range keys from in-memory index tree' (duration: 376.19716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:15.636253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:15.259906Z","time spent":"376.342923ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T01:15:15.636767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.124178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:15:15.636811Z","caller":"traceutil/trace.go:171","msg":"trace[1225567692] linearizableReadLoop","detail":"{readStateIndex:2855; appliedIndex:2854; }","duration":"111.385883ms","start":"2026-07-25T01:15:15.525418Z","end":"2026-07-25T01:15:15.636804Z","steps":["trace[1225567692] 'read index received' (duration: 23.43µs)","trace[1225567692] 'applied index is now lower than readState.Index' (duration: 111.361873ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:15:15.636898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.483745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c56360fc50e12b\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-25T01:15:15.636921Z","caller":"traceutil/trace.go:171","msg":"trace[438220003] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c56360fc50e12b; range_end:; response_count:1; response_revision:2749; }","duration":"111.530286ms","start":"2026-07-25T01:15:15.525385Z","end":"2026-07-25T01:15:15.636916Z","steps":["trace[438220003] 'agreement among raft nodes before linearized reading' (duration: 111.443595ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:15:15.637147Z","caller":"traceutil/trace.go:171","msg":"trace[1566475367] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"207.387921ms","start":"2026-07-25T01:15:15.42975Z","end":"2026-07-25T01:15:15.637138Z","steps":["trace[1566475367] 'process raft request' (duration: 88.836307ms)","trace[1566475367] 'compare' (duration: 117.955276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:15:16.584545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.396213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:15:16.58466Z","caller":"traceutil/trace.go:171","msg":"trace[1951714485] linearizableReadLoop","detail":"{readStateIndex:2857; appliedIndex:2856; }","duration":"770.074052ms","start":"2026-07-25T01:15:15.814569Z","end":"2026-07-25T01:15:16.584643Z","steps":["trace[1951714485] 'read index received' (duration: 448.449157ms)","trace[1951714485] 'applied index is now lower than readState.Index' (duration: 321.623865ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:15:16.585191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.60903ms","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-07-25T01:15:16.585243Z","caller":"traceutil/trace.go:171","msg":"trace[814110204] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"792.70939ms","start":"2026-07-25T01:15:15.792481Z","end":"2026-07-25T01:15:16.585191Z","steps":["trace[814110204] 'process raft request' (duration: 470.583107ms)","trace[814110204] 'compare' (duration: 320.707193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:15:16.585565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:15.792456Z","time spent":"792.948433ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:15:16.585261Z","caller":"traceutil/trace.go:171","msg":"trace[766289947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2751; }","duration":"770.719642ms","start":"2026-07-25T01:15:15.814525Z","end":"2026-07-25T01:15:16.585244Z","steps":["trace[766289947] 'agreement among raft nodes before linearized reading' (duration: 770.497429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:16.585688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:15.814513Z","time spent":"771.146488ms","remote":"127.0.0.1:56632","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":"info","ts":"2026-07-25T01:15:16.619536Z","caller":"traceutil/trace.go:171","msg":"trace[636309921] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"519.342384ms","start":"2026-07-25T01:15:16.100168Z","end":"2026-07-25T01:15:16.619511Z","steps":["trace[636309921] 'process raft request' (duration: 519.113991ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:15:16.619551Z","caller":"traceutil/trace.go:171","msg":"trace[432480484] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"468.874589ms","start":"2026-07-25T01:15:16.150654Z","end":"2026-07-25T01:15:16.619529Z","steps":["trace[432480484] 'process raft request' (duration: 468.749157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:16.619589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.896654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-25T01:15:16.619639Z","caller":"traceutil/trace.go:171","msg":"trace[1768037973] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2753; }","duration":"400.949015ms","start":"2026-07-25T01:15:16.218676Z","end":"2026-07-25T01:15:16.619625Z","steps":["trace[1768037973] 'agreement among raft nodes before linearized reading' (duration: 400.797402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:16.619682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.218611Z","time spent":"401.063336ms","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-25T01:15:16.619688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.10014Z","time spent":"519.456305ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:15:16.61983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.266261ms","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-07-25T01:15:16.619785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.150628Z","time spent":"468.971641ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:15:16.619868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.302371ms","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-07-25T01:15:16.619918Z","caller":"traceutil/trace.go:171","msg":"trace[1873747620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2753; }","duration":"682.397592ms","start":"2026-07-25T01:15:15.937499Z","end":"2026-07-25T01:15:16.619897Z","steps":["trace[1873747620] 'agreement among raft nodes before linearized reading' (duration: 682.142379ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:15:16.619927Z","caller":"traceutil/trace.go:171","msg":"trace[1293488431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2753; }","duration":"756.405712ms","start":"2026-07-25T01:15:15.863508Z","end":"2026-07-25T01:15:16.619913Z","steps":["trace[1293488431] 'agreement among raft nodes before linearized reading' (duration: 756.230919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:16.61997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:15.863485Z","time spent":"756.477233ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T01:15:16.619971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:15.937483Z","time spent":"682.479164ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T01:15:16.620027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.286833ms","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-07-25T01:15:16.620068Z","caller":"traceutil/trace.go:171","msg":"trace[730681993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2753; }","duration":"660.350534ms","start":"2026-07-25T01:15:15.959707Z","end":"2026-07-25T01:15:16.620057Z","steps":["trace[730681993] 'agreement among raft nodes before linearized reading' (duration: 660.247612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:16.620097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:15.959693Z","time spent":"660.397644ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T01:15:16.620201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.73337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-25T01:15:16.62024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.921529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T01:15:16.620291Z","caller":"traceutil/trace.go:171","msg":"trace[929704975] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2753; }","duration":"799.00993ms","start":"2026-07-25T01:15:15.821269Z","end":"2026-07-25T01:15:16.620279Z","steps":["trace[929704975] 'agreement among raft nodes before linearized reading' (duration: 798.181648ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:15:16.62029Z","caller":"traceutil/trace.go:171","msg":"trace[20076661] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2753; }","duration":"757.957654ms","start":"2026-07-25T01:15:15.862311Z","end":"2026-07-25T01:15:16.620269Z","steps":["trace[20076661] 'agreement among raft nodes before linearized reading' (duration: 757.623729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:16.620319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:15.821246Z","time spent":"799.06675ms","remote":"127.0.0.1:56632","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-07-25T01:15:16.620389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:15.862288Z","time spent":"758.059885ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-25T01:15:17.125255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948395870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T01:15:17.449097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.967288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:15:17.449179Z","caller":"traceutil/trace.go:171","msg":"trace[673955529] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"858.191195ms","start":"2026-07-25T01:15:16.590974Z","end":"2026-07-25T01:15:17.449165Z","steps":["trace[673955529] 'process raft request' (duration: 571.097645ms)","trace[673955529] 'compare' (duration: 286.769175ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:15:17.44922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.590954Z","time spent":"858.247915ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:15:17.475594Z","caller":"traceutil/trace.go:171","msg":"trace[1507285840] linearizableReadLoop","detail":"{readStateIndex:2866; appliedIndex:2859; }","duration":"851.211263ms","start":"2026-07-25T01:15:16.624372Z","end":"2026-07-25T01:15:17.475584Z","steps":["trace[1507285840] 'read index received' (duration: 537.686135ms)","trace[1507285840] 'applied index is now lower than readState.Index' (duration: 313.524048ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:15:17.475746Z","caller":"traceutil/trace.go:171","msg":"trace[841885283] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"849.048862ms","start":"2026-07-25T01:15:16.62669Z","end":"2026-07-25T01:15:17.475739Z","steps":["trace[841885283] 'process raft request' (duration: 848.700707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:17.475787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.626672Z","time spent":"849.090332ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:15:17.475884Z","caller":"traceutil/trace.go:171","msg":"trace[448663401] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"849.111193ms","start":"2026-07-25T01:15:16.626766Z","end":"2026-07-25T01:15:17.475878Z","steps":["trace[448663401] 'process raft request' (duration: 848.669946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:17.475917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.626762Z","time spent":"849.136083ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:15:17.475993Z","caller":"traceutil/trace.go:171","msg":"trace[624482649] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"849.183754ms","start":"2026-07-25T01:15:16.626804Z","end":"2026-07-25T01:15:17.475988Z","steps":["trace[624482649] 'process raft request' (duration: 848.654876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:17.476026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.6268Z","time spent":"849.206804ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:15:17.476067Z","caller":"traceutil/trace.go:171","msg":"trace[1201743358] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"849.223615ms","start":"2026-07-25T01:15:16.62684Z","end":"2026-07-25T01:15:17.476063Z","steps":["trace[1201743358] 'process raft request' (duration: 848.653986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:17.476094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.626836Z","time spent":"849.242895ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:15:17.476165Z","caller":"traceutil/trace.go:171","msg":"trace[2013762426] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"848.496004ms","start":"2026-07-25T01:15:16.627663Z","end":"2026-07-25T01:15:17.476159Z","steps":["trace[2013762426] 'process raft request' (duration: 847.856155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:17.476193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.627655Z","time spent":"848.522324ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:15:17.476255Z","caller":"traceutil/trace.go:171","msg":"trace[530894185] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"845.967758ms","start":"2026-07-25T01:15:16.630282Z","end":"2026-07-25T01:15:17.47625Z","steps":["trace[530894185] 'process raft request' (duration: 845.272768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:17.476284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.630246Z","time spent":"846.020979ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:15:17.476631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.786745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T01:15:17.476639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.164092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:15:17.476753Z","caller":"traceutil/trace.go:171","msg":"trace[1678135073] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2760; }","duration":"227.336463ms","start":"2026-07-25T01:15:17.249396Z","end":"2026-07-25T01:15:17.476732Z","steps":["trace[1678135073] 'agreement among raft nodes before linearized reading' (duration: 227.185541ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:15:17.476663Z","caller":"traceutil/trace.go:171","msg":"trace[1590461838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2760; }","duration":"215.848655ms","start":"2026-07-25T01:15:17.260807Z","end":"2026-07-25T01:15:17.476656Z","steps":["trace[1590461838] 'agreement among raft nodes before linearized reading' (duration: 215.796605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:17.476567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.169498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1449"} {"level":"info","ts":"2026-07-25T01:15:17.477174Z","caller":"traceutil/trace.go:171","msg":"trace[404248603] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2760; }","duration":"852.810727ms","start":"2026-07-25T01:15:16.624345Z","end":"2026-07-25T01:15:17.477156Z","steps":["trace[404248603] 'agreement among raft nodes before linearized reading' (duration: 852.062246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:15:17.478731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:15:16.62433Z","time spent":"852.970119ms","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1472,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"info","ts":"2026-07-25T01:16:57.280907Z","caller":"traceutil/trace.go:171","msg":"trace[944592349] transaction","detail":"{read_only:false; response_revision:3275; number_of_response:1; }","duration":"211.641056ms","start":"2026-07-25T01:16:57.069237Z","end":"2026-07-25T01:16:57.280878Z","steps":["trace[944592349] 'process raft request' (duration: 211.489531ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:16:57.304751Z","caller":"traceutil/trace.go:171","msg":"trace[1363332857] transaction","detail":"{read_only:false; response_revision:3276; number_of_response:1; }","duration":"210.480924ms","start":"2026-07-25T01:16:57.094229Z","end":"2026-07-25T01:16:57.30471Z","steps":["trace[1363332857] 'process raft request' (duration: 209.929504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:16:58.191593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.536928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:16:58.191795Z","caller":"traceutil/trace.go:171","msg":"trace[27557024] transaction","detail":"{read_only:false; response_revision:3279; number_of_response:1; }","duration":"176.314802ms","start":"2026-07-25T01:16:58.015459Z","end":"2026-07-25T01:16:58.191774Z","steps":["trace[27557024] 'process raft request' (duration: 176.2571ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:16:58.19191Z","caller":"traceutil/trace.go:171","msg":"trace[1082705875] linearizableReadLoop","detail":"{readStateIndex:3405; appliedIndex:3404; }","duration":"180.719723ms","start":"2026-07-25T01:16:58.011169Z","end":"2026-07-25T01:16:58.191889Z","steps":["trace[1082705875] 'read index received' (duration: 27.727488ms)","trace[1082705875] 'applied index is now lower than readState.Index' (duration: 152.989985ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:16:58.192108Z","caller":"traceutil/trace.go:171","msg":"trace[87657489] transaction","detail":"{read_only:false; response_revision:3278; number_of_response:1; }","duration":"189.16893ms","start":"2026-07-25T01:16:58.002923Z","end":"2026-07-25T01:16:58.192092Z","steps":["trace[87657489] 'process raft request' (duration: 36.01995ms)","trace[87657489] 'compare' (duration: 152.382392ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:16:58.192406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.826748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T01:16:58.192491Z","caller":"traceutil/trace.go:171","msg":"trace[1221718458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3279; }","duration":"188.974834ms","start":"2026-07-25T01:16:58.003497Z","end":"2026-07-25T01:16:58.192471Z","steps":["trace[1221718458] 'agreement among raft nodes before linearized reading' (duration: 188.685173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:16:58.192554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.531179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T01:16:58.192606Z","caller":"traceutil/trace.go:171","msg":"trace[278128787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3279; }","duration":"157.624443ms","start":"2026-07-25T01:16:58.034967Z","end":"2026-07-25T01:16:58.192591Z","steps":["trace[278128787] 'agreement among raft nodes before linearized reading' (duration: 157.477808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:16:58.192788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.363023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-25T01:16:58.192826Z","caller":"traceutil/trace.go:171","msg":"trace[143045247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3279; }","duration":"182.410214ms","start":"2026-07-25T01:16:58.010406Z","end":"2026-07-25T01:16:58.192816Z","steps":["trace[143045247] 'agreement among raft nodes before linearized reading' (duration: 182.315731ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:16:58.418766Z","caller":"traceutil/trace.go:171","msg":"trace[266566167] transaction","detail":"{read_only:false; response_revision:3281; number_of_response:1; }","duration":"221.627181ms","start":"2026-07-25T01:16:58.197107Z","end":"2026-07-25T01:16:58.418734Z","steps":["trace[266566167] 'process raft request' (duration: 221.276948ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:16:58.418769Z","caller":"traceutil/trace.go:171","msg":"trace[2039044308] transaction","detail":"{read_only:false; response_revision:3280; number_of_response:1; }","duration":"221.609ms","start":"2026-07-25T01:16:58.197107Z","end":"2026-07-25T01:16:58.418716Z","steps":["trace[2039044308] 'process raft request' (duration: 179.187257ms)","trace[2039044308] 'compare' (duration: 41.863873ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:16:58.419117Z","caller":"traceutil/trace.go:171","msg":"trace[727399572] transaction","detail":"{read_only:false; response_revision:3282; number_of_response:1; }","duration":"220.740918ms","start":"2026-07-25T01:16:58.198324Z","end":"2026-07-25T01:16:58.419065Z","steps":["trace[727399572] 'process raft request' (duration: 220.150586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:16:58.522042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.474004ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-25T01:16:58.522122Z","caller":"traceutil/trace.go:171","msg":"trace[2061385856] linearizableReadLoop","detail":"{readStateIndex:3409; appliedIndex:3406; }","duration":"303.526429ms","start":"2026-07-25T01:16:58.218581Z","end":"2026-07-25T01:16:58.522108Z","steps":["trace[2061385856] 'read index received' (duration: 157.712566ms)","trace[2061385856] 'applied index is now lower than readState.Index' (duration: 145.812873ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:16:58.522338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.851587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T01:16:58.52243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.84283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-25T01:16:58.522409Z","caller":"traceutil/trace.go:171","msg":"trace[322663741] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:3282; }","duration":"128.973081ms","start":"2026-07-25T01:16:58.393415Z","end":"2026-07-25T01:16:58.522388Z","steps":["trace[322663741] 'agreement among raft nodes before linearized reading' (duration: 128.848996ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:16:58.522465Z","caller":"traceutil/trace.go:171","msg":"trace[252169900] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3282; }","duration":"303.914053ms","start":"2026-07-25T01:16:58.21854Z","end":"2026-07-25T01:16:58.522455Z","steps":["trace[252169900] 'agreement among raft nodes before linearized reading' (duration: 303.682215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:16:58.522499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:16:58.218527Z","time spent":"303.964425ms","remote":"127.0.0.1:56552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-07-25T01:18:12.054051Z","caller":"traceutil/trace.go:171","msg":"trace[1923700188] linearizableReadLoop","detail":"{readStateIndex:3948; appliedIndex:3947; }","duration":"118.849337ms","start":"2026-07-25T01:18:11.935177Z","end":"2026-07-25T01:18:12.054026Z","steps":["trace[1923700188] 'read index received' (duration: 28.835704ms)","trace[1923700188] 'applied index is now lower than readState.Index' (duration: 90.012773ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:18:12.054134Z","caller":"traceutil/trace.go:171","msg":"trace[387582891] transaction","detail":"{read_only:false; response_revision:3799; number_of_response:1; }","duration":"119.291146ms","start":"2026-07-25T01:18:11.934806Z","end":"2026-07-25T01:18:12.054098Z","steps":["trace[387582891] 'process raft request' (duration: 29.19797ms)","trace[387582891] 'compare' (duration: 89.564653ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:18:12.054229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.022905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:18:12.054282Z","caller":"traceutil/trace.go:171","msg":"trace[549912455] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3799; }","duration":"119.112049ms","start":"2026-07-25T01:18:11.935138Z","end":"2026-07-25T01:18:12.054251Z","steps":["trace[549912455] 'agreement among raft nodes before linearized reading' (duration: 118.986614ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:18:12.310201Z","caller":"traceutil/trace.go:171","msg":"trace[1708364453] transaction","detail":"{read_only:false; response_revision:3801; number_of_response:1; }","duration":"168.386986ms","start":"2026-07-25T01:18:12.141776Z","end":"2026-07-25T01:18:12.310163Z","steps":["trace[1708364453] 'process raft request' (duration: 83.185533ms)","trace[1708364453] 'compare' (duration: 84.92222ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:18:12.334849Z","caller":"traceutil/trace.go:171","msg":"trace[2019066787] linearizableReadLoop","detail":"{readStateIndex:3951; appliedIndex:3949; }","duration":"169.868582ms","start":"2026-07-25T01:18:12.164951Z","end":"2026-07-25T01:18:12.334819Z","steps":["trace[2019066787] 'read index received' (duration: 59.939874ms)","trace[2019066787] 'applied index is now lower than readState.Index' (duration: 109.927198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:18:12.335062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.04469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:18:12.335189Z","caller":"traceutil/trace.go:171","msg":"trace[1222009895] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3802; }","duration":"170.188616ms","start":"2026-07-25T01:18:12.16494Z","end":"2026-07-25T01:18:12.335128Z","steps":["trace[1222009895] 'agreement among raft nodes before linearized reading' (duration: 169.987867ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:18:12.335646Z","caller":"traceutil/trace.go:171","msg":"trace[1016949776] transaction","detail":"{read_only:false; response_revision:3802; number_of_response:1; }","duration":"183.255234ms","start":"2026-07-25T01:18:12.152371Z","end":"2026-07-25T01:18:12.335627Z","steps":["trace[1016949776] 'process raft request' (duration: 181.791869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:18:12.639578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.40669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T01:18:12.639885Z","caller":"traceutil/trace.go:171","msg":"trace[1609660320] transaction","detail":"{read_only:false; response_revision:3803; number_of_response:1; }","duration":"258.121129ms","start":"2026-07-25T01:18:12.381717Z","end":"2026-07-25T01:18:12.639838Z","steps":["trace[1609660320] 'process raft request' (duration: 135.331062ms)","trace[1609660320] 'compare' (duration: 122.111787ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:18:12.640028Z","caller":"traceutil/trace.go:171","msg":"trace[1297764511] linearizableReadLoop","detail":"{readStateIndex:3952; appliedIndex:3951; }","duration":"252.142755ms","start":"2026-07-25T01:18:12.387844Z","end":"2026-07-25T01:18:12.639986Z","steps":["trace[1297764511] 'read index received' (duration: 129.315686ms)","trace[1297764511] 'applied index is now lower than readState.Index' (duration: 122.824789ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:18:12.640327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.452888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:18:12.640437Z","caller":"traceutil/trace.go:171","msg":"trace[1460181786] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com; range_end:; response_count:0; response_revision:3803; }","duration":"252.590985ms","start":"2026-07-25T01:18:12.387824Z","end":"2026-07-25T01:18:12.640415Z","steps":["trace[1460181786] 'agreement among raft nodes before linearized reading' (duration: 252.323473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:18:12.641164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.728603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-25T01:18:12.641233Z","caller":"traceutil/trace.go:171","msg":"trace[1313795201] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3804; }","duration":"208.799956ms","start":"2026-07-25T01:18:12.432408Z","end":"2026-07-25T01:18:12.641208Z","steps":["trace[1313795201] 'agreement among raft nodes before linearized reading' (duration: 208.534344ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:18:12.641445Z","caller":"traceutil/trace.go:171","msg":"trace[1741145662] transaction","detail":"{read_only:false; response_revision:3804; number_of_response:1; }","duration":"171.19008ms","start":"2026-07-25T01:18:12.470228Z","end":"2026-07-25T01:18:12.641418Z","steps":["trace[1741145662] 'process raft request' (duration: 169.976907ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:18:22.614973Z","caller":"traceutil/trace.go:171","msg":"trace[694938330] transaction","detail":"{read_only:false; response_revision:3886; number_of_response:1; }","duration":"104.385741ms","start":"2026-07-25T01:18:22.510528Z","end":"2026-07-25T01:18:22.614913Z","steps":["trace[694938330] 'process raft request' (duration: 94.607481ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:18:22.880611Z","caller":"traceutil/trace.go:171","msg":"trace[45414121] transaction","detail":"{read_only:false; response_revision:3889; number_of_response:1; }","duration":"173.073215ms","start":"2026-07-25T01:18:22.70749Z","end":"2026-07-25T01:18:22.880563Z","steps":["trace[45414121] 'process raft request' (duration: 103.190168ms)","trace[45414121] 'compare' (duration: 69.659367ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:18:24.694303Z","caller":"traceutil/trace.go:171","msg":"trace[784757146] transaction","detail":"{read_only:false; response_revision:3899; number_of_response:1; }","duration":"116.790417ms","start":"2026-07-25T01:18:24.577487Z","end":"2026-07-25T01:18:24.694278Z","steps":["trace[784757146] 'process raft request' (duration: 116.610119ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:18:24.840076Z","caller":"traceutil/trace.go:171","msg":"trace[2126692576] transaction","detail":"{read_only:false; response_revision:3900; number_of_response:1; }","duration":"123.827685ms","start":"2026-07-25T01:18:24.716211Z","end":"2026-07-25T01:18:24.840039Z","steps":["trace[2126692576] 'process raft request' (duration: 33.079875ms)","trace[2126692576] 'compare' (duration: 90.31794ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:00.626068Z","caller":"traceutil/trace.go:171","msg":"trace[85314245] transaction","detail":"{read_only:false; response_revision:4430; number_of_response:1; }","duration":"135.547729ms","start":"2026-07-25T01:19:00.490497Z","end":"2026-07-25T01:19:00.626045Z","steps":["trace[85314245] 'process raft request' (duration: 135.1374ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:00.627004Z","caller":"traceutil/trace.go:171","msg":"trace[1345963465] transaction","detail":"{read_only:false; response_revision:4431; number_of_response:1; }","duration":"112.467345ms","start":"2026-07-25T01:19:00.514515Z","end":"2026-07-25T01:19:00.626982Z","steps":["trace[1345963465] 'process raft request' (duration: 112.169011ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:01.783746Z","caller":"traceutil/trace.go:171","msg":"trace[771662690] transaction","detail":"{read_only:false; response_revision:4471; number_of_response:1; }","duration":"260.038219ms","start":"2026-07-25T01:19:01.52368Z","end":"2026-07-25T01:19:01.783719Z","steps":["trace[771662690] 'process raft request' (duration: 259.819579ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:01.787968Z","caller":"traceutil/trace.go:171","msg":"trace[2090686116] linearizableReadLoop","detail":"{readStateIndex:4649; appliedIndex:4646; }","duration":"103.337927ms","start":"2026-07-25T01:19:01.684587Z","end":"2026-07-25T01:19:01.787925Z","steps":["trace[2090686116] 'read index received' (duration: 98.937519ms)","trace[2090686116] 'applied index is now lower than readState.Index' (duration: 4.399258ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:01.788337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.707034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-patch-dwgnc\" ","response":"range_response_count:1 size:4332"} {"level":"info","ts":"2026-07-25T01:19:01.788404Z","caller":"traceutil/trace.go:171","msg":"trace[406161161] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-patch-dwgnc; range_end:; response_count:1; response_revision:4473; }","duration":"103.855581ms","start":"2026-07-25T01:19:01.684533Z","end":"2026-07-25T01:19:01.788388Z","steps":["trace[406161161] 'agreement among raft nodes before linearized reading' (duration: 103.525236ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:01.788399Z","caller":"traceutil/trace.go:171","msg":"trace[1615981895] transaction","detail":"{read_only:false; response_revision:4473; number_of_response:1; }","duration":"209.062818ms","start":"2026-07-25T01:19:01.579223Z","end":"2026-07-25T01:19:01.788285Z","steps":["trace[1615981895] 'process raft request' (duration: 208.623917ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:01.788567Z","caller":"traceutil/trace.go:171","msg":"trace[1452371246] transaction","detail":"{read_only:false; response_revision:4472; number_of_response:1; }","duration":"225.089828ms","start":"2026-07-25T01:19:01.563446Z","end":"2026-07-25T01:19:01.788535Z","steps":["trace[1452371246] 'process raft request' (duration: 224.238738ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:02.420715Z","caller":"traceutil/trace.go:171","msg":"trace[429007846] transaction","detail":"{read_only:false; response_revision:4508; number_of_response:1; }","duration":"105.930716ms","start":"2026-07-25T01:19:02.314756Z","end":"2026-07-25T01:19:02.420686Z","steps":["trace[429007846] 'process raft request' (duration: 105.862832ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:02.420874Z","caller":"traceutil/trace.go:171","msg":"trace[1916561729] transaction","detail":"{read_only:false; response_revision:4507; number_of_response:1; }","duration":"120.09621ms","start":"2026-07-25T01:19:02.300746Z","end":"2026-07-25T01:19:02.420842Z","steps":["trace[1916561729] 'process raft request' (duration: 97.954567ms)","trace[1916561729] 'compare' (duration: 21.717193ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:02.420908Z","caller":"traceutil/trace.go:171","msg":"trace[1168541667] linearizableReadLoop","detail":"{readStateIndex:4684; appliedIndex:4683; }","duration":"119.550314ms","start":"2026-07-25T01:19:02.301312Z","end":"2026-07-25T01:19:02.420862Z","steps":["trace[1168541667] 'read index received' (duration: 97.404291ms)","trace[1168541667] 'applied index is now lower than readState.Index' (duration: 22.126762ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:02.421117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.779375ms","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-07-25T01:19:02.421194Z","caller":"traceutil/trace.go:171","msg":"trace[1035854551] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4508; }","duration":"119.904161ms","start":"2026-07-25T01:19:02.301268Z","end":"2026-07-25T01:19:02.421172Z","steps":["trace[1035854551] 'agreement among raft nodes before linearized reading' (duration: 119.763494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:02.422603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.842707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-pmv8l.18c56340c6f66151\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-07-25T01:19:02.422672Z","caller":"traceutil/trace.go:171","msg":"trace[1507006219] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-pmv8l.18c56340c6f66151; range_end:; response_count:1; response_revision:4508; }","duration":"120.004195ms","start":"2026-07-25T01:19:02.30265Z","end":"2026-07-25T01:19:02.422654Z","steps":["trace[1507006219] 'agreement among raft nodes before linearized reading' (duration: 119.669549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:02.422694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.306254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:46331"} {"level":"info","ts":"2026-07-25T01:19:02.422783Z","caller":"traceutil/trace.go:171","msg":"trace[68261104] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4508; }","duration":"110.43754ms","start":"2026-07-25T01:19:02.312323Z","end":"2026-07-25T01:19:02.422761Z","steps":["trace[68261104] 'agreement among raft nodes before linearized reading' (duration: 110.067293ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:03.91721Z","caller":"traceutil/trace.go:171","msg":"trace[1257316634] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"199.152088ms","start":"2026-07-25T01:19:03.718029Z","end":"2026-07-25T01:19:03.917182Z","steps":["trace[1257316634] 'process raft request' (duration: 198.966319ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:04.238818Z","caller":"traceutil/trace.go:171","msg":"trace[1897295770] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"400.577207ms","start":"2026-07-25T01:19:03.838205Z","end":"2026-07-25T01:19:04.238783Z","steps":["trace[1897295770] 'process raft request' (duration: 400.460882ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:04.239018Z","caller":"traceutil/trace.go:171","msg":"trace[634786548] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"392.86834ms","start":"2026-07-25T01:19:03.846114Z","end":"2026-07-25T01:19:04.238982Z","steps":["trace[634786548] 'process raft request' (duration: 392.619418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:04.239037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:03.83818Z","time spent":"400.737905ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:19:04.239018Z","caller":"traceutil/trace.go:171","msg":"trace[1403223827] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"443.681019ms","start":"2026-07-25T01:19:03.795258Z","end":"2026-07-25T01:19:04.238939Z","steps":["trace[1403223827] 'process raft request' (duration: 392.477779ms)","trace[1403223827] 'compare' (duration: 50.683115ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:04.239216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:03.846097Z","time spent":"392.998466ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:19:04.239272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:03.795228Z","time spent":"443.899919ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:19:10.527447Z","caller":"traceutil/trace.go:171","msg":"trace[941827181] linearizableReadLoop","detail":"{readStateIndex:4871; appliedIndex:4870; }","duration":"248.240724ms","start":"2026-07-25T01:19:10.279175Z","end":"2026-07-25T01:19:10.527416Z","steps":["trace[941827181] 'read index received' (duration: 248.067126ms)","trace[941827181] 'applied index is now lower than readState.Index' (duration: 171.288µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:10.527534Z","caller":"traceutil/trace.go:171","msg":"trace[1527557838] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"248.471735ms","start":"2026-07-25T01:19:10.279014Z","end":"2026-07-25T01:19:10.527486Z","steps":["trace[1527557838] 'process raft request' (duration: 248.169811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:10.527839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.636374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60499"} {"level":"info","ts":"2026-07-25T01:19:10.527912Z","caller":"traceutil/trace.go:171","msg":"trace[1747513784] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4687; }","duration":"248.732338ms","start":"2026-07-25T01:19:10.279162Z","end":"2026-07-25T01:19:10.527895Z","steps":["trace[1747513784] 'agreement among raft nodes before linearized reading' (duration: 248.373261ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:10.922021Z","caller":"traceutil/trace.go:171","msg":"trace[1180432270] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"642.50352ms","start":"2026-07-25T01:19:10.279487Z","end":"2026-07-25T01:19:10.92199Z","steps":["trace[1180432270] 'process raft request' (duration: 620.719955ms)","trace[1180432270] 'compare' (duration: 21.608917ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:10.922235Z","caller":"traceutil/trace.go:171","msg":"trace[700664212] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"641.885201ms","start":"2026-07-25T01:19:10.280308Z","end":"2026-07-25T01:19:10.922193Z","steps":["trace[700664212] 'process raft request' (duration: 641.641409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:10.923642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:10.280302Z","time spent":"643.199394ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:19:10.924239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:10.279456Z","time spent":"643.847875ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:19:10.953086Z","caller":"traceutil/trace.go:171","msg":"trace[1055983718] linearizableReadLoop","detail":"{readStateIndex:4874; appliedIndex:4871; }","duration":"163.846867ms","start":"2026-07-25T01:19:10.789208Z","end":"2026-07-25T01:19:10.953055Z","steps":["trace[1055983718] 'read index received' (duration: 111.027174ms)","trace[1055983718] 'applied index is now lower than readState.Index' (duration: 52.818063ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:10.95312Z","caller":"traceutil/trace.go:171","msg":"trace[772910497] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"409.715948ms","start":"2026-07-25T01:19:10.54336Z","end":"2026-07-25T01:19:10.953076Z","steps":["trace[772910497] 'process raft request' (duration: 409.488817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:10.953325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:10.543336Z","time spent":"409.873265ms","remote":"127.0.0.1:47246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:19:10.953377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.159102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-s9fh9\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-07-25T01:19:10.953481Z","caller":"traceutil/trace.go:171","msg":"trace[1418722424] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-s9fh9; range_end:; response_count:1; response_revision:4690; }","duration":"164.310429ms","start":"2026-07-25T01:19:10.789149Z","end":"2026-07-25T01:19:10.953459Z","steps":["trace[1418722424] 'agreement among raft nodes before linearized reading' (duration: 164.025286ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:11.286256Z","caller":"traceutil/trace.go:171","msg":"trace[435042510] linearizableReadLoop","detail":"{readStateIndex:4876; appliedIndex:4875; }","duration":"163.403384ms","start":"2026-07-25T01:19:11.122825Z","end":"2026-07-25T01:19:11.286229Z","steps":["trace[435042510] 'read index received' (duration: 163.028716ms)","trace[435042510] 'applied index is now lower than readState.Index' (duration: 373.828µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:11.286476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.627945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T01:19:11.286479Z","caller":"traceutil/trace.go:171","msg":"trace[1359494615] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"167.366944ms","start":"2026-07-25T01:19:11.118966Z","end":"2026-07-25T01:19:11.286333Z","steps":["trace[1359494615] 'process raft request' (duration: 167.068299ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:11.286585Z","caller":"traceutil/trace.go:171","msg":"trace[984868255] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4692; }","duration":"163.804933ms","start":"2026-07-25T01:19:11.122764Z","end":"2026-07-25T01:19:11.286569Z","steps":["trace[984868255] 'agreement among raft nodes before linearized reading' (duration: 163.638185ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:16.084071Z","caller":"traceutil/trace.go:171","msg":"trace[2131803190] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4964; }","duration":"121.828606ms","start":"2026-07-25T01:19:15.962214Z","end":"2026-07-25T01:19:16.084043Z","steps":["trace[2131803190] 'read index received' (duration: 90.070514ms)","trace[2131803190] 'applied index is now lower than readState.Index' (duration: 31.757332ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:16.084272Z","caller":"traceutil/trace.go:171","msg":"trace[387464482] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"122.812143ms","start":"2026-07-25T01:19:15.961405Z","end":"2026-07-25T01:19:16.084217Z","steps":["trace[387464482] 'process raft request' (duration: 90.961567ms)","trace[387464482] 'compare' (duration: 31.534041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:16.084429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.159132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-07-25T01:19:16.084503Z","caller":"traceutil/trace.go:171","msg":"trace[2096256799] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4780; }","duration":"122.289068ms","start":"2026-07-25T01:19:15.962203Z","end":"2026-07-25T01:19:16.084492Z","steps":["trace[2096256799] 'agreement among raft nodes before linearized reading' (duration: 122.009984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:16.084666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.376155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:19:16.084807Z","caller":"traceutil/trace.go:171","msg":"trace[1912534814] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4780; }","duration":"115.562933ms","start":"2026-07-25T01:19:15.969218Z","end":"2026-07-25T01:19:16.084781Z","steps":["trace[1912534814] 'agreement among raft nodes before linearized reading' (duration: 115.233488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:17.583013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.86416ms","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:1935"} {"level":"info","ts":"2026-07-25T01:19:17.583122Z","caller":"traceutil/trace.go:171","msg":"trace[401150937] 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:4796; }","duration":"730.989966ms","start":"2026-07-25T01:19:16.852104Z","end":"2026-07-25T01:19:17.583094Z","steps":["trace[401150937] 'range keys from in-memory index tree' (duration: 730.608127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:17.583203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:16.852054Z","time spent":"731.128032ms","remote":"127.0.0.1:56530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-07-25T01:19:17.583872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.791707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca034809-6dff-438f-afbe-39dbfe641288\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-25T01:19:17.583987Z","caller":"traceutil/trace.go:171","msg":"trace[205209193] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca034809-6dff-438f-afbe-39dbfe641288; range_end:; response_count:1; response_revision:4796; }","duration":"644.985976ms","start":"2026-07-25T01:19:16.938973Z","end":"2026-07-25T01:19:17.583959Z","steps":["trace[205209193] 'range keys from in-memory index tree' (duration: 644.057172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:17.58404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:16.938947Z","time spent":"645.082401ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca034809-6dff-438f-afbe-39dbfe641288\" "} {"level":"warn","ts":"2026-07-25T01:19:17.584784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.368757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-595589f766-5z75n.18c5639d98397124\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-25T01:19:17.584834Z","caller":"traceutil/trace.go:171","msg":"trace[976384270] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-595589f766-5z75n.18c5639d98397124; range_end:; response_count:1; response_revision:4796; }","duration":"729.435571ms","start":"2026-07-25T01:19:16.855386Z","end":"2026-07-25T01:19:17.584822Z","steps":["trace[976384270] 'range keys from in-memory index tree' (duration: 727.410433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:17.584867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:16.855376Z","time spent":"729.482253ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":921,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-595589f766-5z75n.18c5639d98397124\" "} {"level":"warn","ts":"2026-07-25T01:19:17.585116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.002185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-07-25T01:19:17.585135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.076091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-07-25T01:19:17.58567Z","caller":"traceutil/trace.go:171","msg":"trace[1265908800] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4796; }","duration":"736.632048ms","start":"2026-07-25T01:19:16.849002Z","end":"2026-07-25T01:19:17.585634Z","steps":["trace[1265908800] 'range keys from in-memory index tree' (duration: 733.693606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:17.585789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:16.84898Z","time spent":"736.772185ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12998,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-07-25T01:19:17.585818Z","caller":"traceutil/trace.go:171","msg":"trace[1918009421] linearizableReadLoop","detail":"{readStateIndex:4983; appliedIndex:4981; }","duration":"326.590362ms","start":"2026-07-25T01:19:17.259195Z","end":"2026-07-25T01:19:17.585785Z","steps":["trace[1918009421] 'read index received' (duration: 32.681539ms)","trace[1918009421] 'applied index is now lower than readState.Index' (duration: 293.905473ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:17.585919Z","caller":"traceutil/trace.go:171","msg":"trace[1196779429] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"619.312498ms","start":"2026-07-25T01:19:16.966589Z","end":"2026-07-25T01:19:17.585901Z","steps":["trace[1196779429] 'process raft request' (duration: 618.893158ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.585721Z","caller":"traceutil/trace.go:171","msg":"trace[1083678470] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"639.45119ms","start":"2026-07-25T01:19:16.946154Z","end":"2026-07-25T01:19:17.585606Z","steps":["trace[1083678470] 'process raft request' (duration: 295.487318ms)","trace[1083678470] 'compare' (duration: 341.212749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:17.586056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:16.96656Z","time spent":"619.404692ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:19:17.586082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.886947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:19:17.586152Z","caller":"traceutil/trace.go:171","msg":"trace[1451684500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4798; }","duration":"327.002142ms","start":"2026-07-25T01:19:17.25913Z","end":"2026-07-25T01:19:17.586132Z","steps":["trace[1451684500] 'agreement among raft nodes before linearized reading' (duration: 326.890147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:17.586203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:17.259103Z","time spent":"327.088786ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T01:19:17.586219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.734685ms","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-07-25T01:19:17.586251Z","caller":"traceutil/trace.go:171","msg":"trace[1195019813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4798; }","duration":"223.790288ms","start":"2026-07-25T01:19:17.362453Z","end":"2026-07-25T01:19:17.586243Z","steps":["trace[1195019813] 'agreement among raft nodes before linearized reading' (duration: 223.63147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:17.586308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.687547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-25T01:19:17.586336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:16.946114Z","time spent":"639.959034ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:19:17.586577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.906051ms","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-07-25T01:19:17.586475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.81568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T01:19:17.586617Z","caller":"traceutil/trace.go:171","msg":"trace[1733077623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4798; }","duration":"224.995036ms","start":"2026-07-25T01:19:17.36161Z","end":"2026-07-25T01:19:17.586605Z","steps":["trace[1733077623] 'agreement among raft nodes before linearized reading' (duration: 224.880381ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.586662Z","caller":"traceutil/trace.go:171","msg":"trace[828883747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4798; }","duration":"224.001648ms","start":"2026-07-25T01:19:17.362634Z","end":"2026-07-25T01:19:17.586636Z","steps":["trace[828883747] 'agreement among raft nodes before linearized reading' (duration: 223.799618ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.586614Z","caller":"traceutil/trace.go:171","msg":"trace[1151370495] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4798; }","duration":"256.851054ms","start":"2026-07-25T01:19:17.329555Z","end":"2026-07-25T01:19:17.586406Z","steps":["trace[1151370495] 'agreement among raft nodes before linearized reading' (duration: 256.523069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:17.586738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.009376ms","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-07-25T01:19:17.586769Z","caller":"traceutil/trace.go:171","msg":"trace[71671167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4798; }","duration":"231.056209ms","start":"2026-07-25T01:19:17.355705Z","end":"2026-07-25T01:19:17.586762Z","steps":["trace[71671167] 'agreement among raft nodes before linearized reading' (duration: 230.997906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:17.586676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.008736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T01:19:17.586829Z","caller":"traceutil/trace.go:171","msg":"trace[1207782570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4798; }","duration":"225.190805ms","start":"2026-07-25T01:19:17.361621Z","end":"2026-07-25T01:19:17.586812Z","steps":["trace[1207782570] 'agreement among raft nodes before linearized reading' (duration: 225.010946ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.766151Z","caller":"traceutil/trace.go:171","msg":"trace[530968508] linearizableReadLoop","detail":"{readStateIndex:4984; appliedIndex:4983; }","duration":"175.432203ms","start":"2026-07-25T01:19:17.590686Z","end":"2026-07-25T01:19:17.766118Z","steps":["trace[530968508] 'read index received' (duration: 157.688966ms)","trace[530968508] 'applied index is now lower than readState.Index' (duration: 17.741647ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:17.766276Z","caller":"traceutil/trace.go:171","msg":"trace[735882104] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"175.854223ms","start":"2026-07-25T01:19:17.590407Z","end":"2026-07-25T01:19:17.766261Z","steps":["trace[735882104] 'process raft request' (duration: 157.970299ms)","trace[735882104] 'compare' (duration: 16.931788ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:17.766571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.846512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:19:17.766665Z","caller":"traceutil/trace.go:171","msg":"trace[842145760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4799; }","duration":"176.00944ms","start":"2026-07-25T01:19:17.590632Z","end":"2026-07-25T01:19:17.766641Z","steps":["trace[842145760] 'agreement among raft nodes before linearized reading' (duration: 175.764758ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.804451Z","caller":"traceutil/trace.go:171","msg":"trace[1407275152] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"211.246052ms","start":"2026-07-25T01:19:17.593176Z","end":"2026-07-25T01:19:17.804422Z","steps":["trace[1407275152] 'process raft request' (duration: 211.134526ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.804761Z","caller":"traceutil/trace.go:171","msg":"trace[1264425977] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"211.583228ms","start":"2026-07-25T01:19:17.593154Z","end":"2026-07-25T01:19:17.804737Z","steps":["trace[1264425977] 'process raft request' (duration: 210.905525ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.804929Z","caller":"traceutil/trace.go:171","msg":"trace[1772734237] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"211.691043ms","start":"2026-07-25T01:19:17.593199Z","end":"2026-07-25T01:19:17.80489Z","steps":["trace[1772734237] 'process raft request' (duration: 211.166698ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.805485Z","caller":"traceutil/trace.go:171","msg":"trace[1118758941] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"212.042141ms","start":"2026-07-25T01:19:17.593419Z","end":"2026-07-25T01:19:17.805461Z","steps":["trace[1118758941] 'process raft request' (duration: 211.385489ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.805525Z","caller":"traceutil/trace.go:171","msg":"trace[1686256335] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"211.900934ms","start":"2026-07-25T01:19:17.593605Z","end":"2026-07-25T01:19:17.805506Z","steps":["trace[1686256335] 'process raft request' (duration: 211.719005ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.80605Z","caller":"traceutil/trace.go:171","msg":"trace[1855478055] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"212.416898ms","start":"2026-07-25T01:19:17.593617Z","end":"2026-07-25T01:19:17.806034Z","steps":["trace[1855478055] 'process raft request' (duration: 211.750506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:17.807819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.727848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-25T01:19:17.80788Z","caller":"traceutil/trace.go:171","msg":"trace[506882714] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4806; }","duration":"162.854065ms","start":"2026-07-25T01:19:17.645009Z","end":"2026-07-25T01:19:17.807863Z","steps":["trace[506882714] 'agreement among raft nodes before linearized reading' (duration: 162.659875ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:17.808098Z","caller":"traceutil/trace.go:171","msg":"trace[216438027] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"208.795663ms","start":"2026-07-25T01:19:17.59929Z","end":"2026-07-25T01:19:17.808085Z","steps":["trace[216438027] 'process raft request' (duration: 206.374917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:19.436981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176089654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:19:19.437079Z","caller":"traceutil/trace.go:171","msg":"trace[1161954985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4815; }","duration":"1.176258452s","start":"2026-07-25T01:19:18.260798Z","end":"2026-07-25T01:19:19.437056Z","steps":["trace[1161954985] 'range keys from in-memory index tree' (duration: 1.17599482s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:19.437127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:18.260775Z","time spent":"1.176342816s","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T01:19:19.508472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.042108ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-25T01:19:19.508854Z","caller":"traceutil/trace.go:171","msg":"trace[1771225678] linearizableReadLoop","detail":"{readStateIndex:5002; appliedIndex:5000; }","duration":"916.610375ms","start":"2026-07-25T01:19:18.59222Z","end":"2026-07-25T01:19:19.508831Z","steps":["trace[1771225678] 'read index received' (duration: 13.197249ms)","trace[1771225678] 'applied index is now lower than readState.Index' (duration: 903.411695ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:19.508956Z","caller":"traceutil/trace.go:171","msg":"trace[440492896] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"952.256797ms","start":"2026-07-25T01:19:18.556684Z","end":"2026-07-25T01:19:19.508941Z","steps":["trace[440492896] 'process raft request' (duration: 951.952472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:19.509087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:18.556646Z","time spent":"952.351632ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:19:19.509182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.93489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca034809-6dff-438f-afbe-39dbfe641288\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-07-25T01:19:19.509275Z","caller":"traceutil/trace.go:171","msg":"trace[771103068] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca034809-6dff-438f-afbe-39dbfe641288; range_end:; response_count:1; response_revision:4816; }","duration":"917.079117ms","start":"2026-07-25T01:19:18.592173Z","end":"2026-07-25T01:19:19.509252Z","steps":["trace[771103068] 'agreement among raft nodes before linearized reading' (duration: 916.809074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:19.509331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:18.592158Z","time spent":"917.159931ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3789,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca034809-6dff-438f-afbe-39dbfe641288\" "} {"level":"warn","ts":"2026-07-25T01:19:19.526122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.883232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-s9fh9.18c5639ca7c56766\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-07-25T01:19:19.526113Z","caller":"traceutil/trace.go:171","msg":"trace[1231884804] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"201.015117ms","start":"2026-07-25T01:19:19.32505Z","end":"2026-07-25T01:19:19.526065Z","steps":["trace[1231884804] 'process raft request' (duration: 200.719462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:19.526185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.674517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:19:19.526203Z","caller":"traceutil/trace.go:171","msg":"trace[594730223] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-s9fh9.18c5639ca7c56766; range_end:; response_count:1; response_revision:4817; }","duration":"194.011988ms","start":"2026-07-25T01:19:19.332163Z","end":"2026-07-25T01:19:19.526175Z","steps":["trace[594730223] 'agreement among raft nodes before linearized reading' (duration: 193.829809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:19.526297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.656706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-07-25T01:19:19.526348Z","caller":"traceutil/trace.go:171","msg":"trace[1991938037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4817; }","duration":"233.749ms","start":"2026-07-25T01:19:19.292456Z","end":"2026-07-25T01:19:19.526205Z","steps":["trace[1991938037] 'agreement among raft nodes before linearized reading' (duration: 233.715449ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:19.526362Z","caller":"traceutil/trace.go:171","msg":"trace[527125391] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4817; }","duration":"651.768152ms","start":"2026-07-25T01:19:18.874572Z","end":"2026-07-25T01:19:19.52634Z","steps":["trace[527125391] 'agreement among raft nodes before linearized reading' (duration: 651.614525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:19.526412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:18.874534Z","time spent":"651.868737ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16256,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-25T01:19:19.526143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.153171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-07-25T01:19:19.526124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.570154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-25T01:19:19.526589Z","caller":"traceutil/trace.go:171","msg":"trace[994762062] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4817; }","duration":"646.617603ms","start":"2026-07-25T01:19:18.879952Z","end":"2026-07-25T01:19:19.526569Z","steps":["trace[994762062] 'agreement among raft nodes before linearized reading' (duration: 646.064656ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:19.526599Z","caller":"traceutil/trace.go:171","msg":"trace[157737096] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4817; }","duration":"874.11093ms","start":"2026-07-25T01:19:18.652472Z","end":"2026-07-25T01:19:19.526583Z","steps":["trace[157737096] 'agreement among raft nodes before linearized reading' (duration: 873.458838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:19.526638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:18.87994Z","time spent":"646.684757ms","remote":"127.0.0.1:56530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-25T01:19:19.526673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:18.652445Z","time spent":"874.187234ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-25T01:19:19.769665Z","caller":"traceutil/trace.go:171","msg":"trace[1587284256] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"228.096687ms","start":"2026-07-25T01:19:19.541499Z","end":"2026-07-25T01:19:19.769596Z","steps":["trace[1587284256] 'process raft request' (duration: 227.873646ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:19.777978Z","caller":"traceutil/trace.go:171","msg":"trace[1540011458] linearizableReadLoop","detail":"{readStateIndex:5008; appliedIndex:5004; }","duration":"154.017533ms","start":"2026-07-25T01:19:19.623939Z","end":"2026-07-25T01:19:19.777957Z","steps":["trace[1540011458] 'read index received' (duration: 145.421557ms)","trace[1540011458] 'applied index is now lower than readState.Index' (duration: 8.594906ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:19.778177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.217372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:19:19.778206Z","caller":"traceutil/trace.go:171","msg":"trace[174506004] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4822; }","duration":"154.322008ms","start":"2026-07-25T01:19:19.623875Z","end":"2026-07-25T01:19:19.778197Z","steps":["trace[174506004] 'agreement among raft nodes before linearized reading' (duration: 154.166251ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:19.778439Z","caller":"traceutil/trace.go:171","msg":"trace[1096275010] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"182.470739ms","start":"2026-07-25T01:19:19.59596Z","end":"2026-07-25T01:19:19.77843Z","steps":["trace[1096275010] 'process raft request' (duration: 181.338175ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:19.778421Z","caller":"traceutil/trace.go:171","msg":"trace[650498858] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"183.616645ms","start":"2026-07-25T01:19:19.594764Z","end":"2026-07-25T01:19:19.778381Z","steps":["trace[650498858] 'process raft request' (duration: 182.47609ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:19.778545Z","caller":"traceutil/trace.go:171","msg":"trace[950315624] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"215.436555ms","start":"2026-07-25T01:19:19.56308Z","end":"2026-07-25T01:19:19.778517Z","steps":["trace[950315624] 'process raft request' (duration: 214.065788ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:22.910751Z","caller":"traceutil/trace.go:171","msg":"trace[357868794] linearizableReadLoop","detail":"{readStateIndex:5048; appliedIndex:5047; }","duration":"118.638517ms","start":"2026-07-25T01:19:22.792086Z","end":"2026-07-25T01:19:22.910724Z","steps":["trace[357868794] 'read index received' (duration: 118.416516ms)","trace[357868794] 'applied index is now lower than readState.Index' (duration: 220.391µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:22.910972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.866968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T01:19:22.911013Z","caller":"traceutil/trace.go:171","msg":"trace[248148167] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4862; }","duration":"118.934631ms","start":"2026-07-25T01:19:22.792063Z","end":"2026-07-25T01:19:22.910998Z","steps":["trace[248148167] 'agreement among raft nodes before linearized reading' (duration: 118.792974ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:22.911423Z","caller":"traceutil/trace.go:171","msg":"trace[508634992] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"378.97789ms","start":"2026-07-25T01:19:22.532258Z","end":"2026-07-25T01:19:22.911236Z","steps":["trace[508634992] 'process raft request' (duration: 378.210023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:22.911979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:22.532229Z","time spent":"379.614951ms","remote":"127.0.0.1:56548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T01:19:33.626421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833387948401305,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T01:19:33.671441Z","caller":"traceutil/trace.go:171","msg":"trace[2023007023] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"753.891143ms","start":"2026-07-25T01:19:32.91752Z","end":"2026-07-25T01:19:33.671412Z","steps":["trace[2023007023] 'process raft request' (duration: 753.691303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:33.672748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:32.917489Z","time spent":"754.04157ms","remote":"127.0.0.1:56632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T01:19:33.680248Z","caller":"traceutil/trace.go:171","msg":"trace[620975539] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5180; }","duration":"554.783538ms","start":"2026-07-25T01:19:33.125437Z","end":"2026-07-25T01:19:33.68022Z","steps":["trace[620975539] 'read index received' (duration: 545.779877ms)","trace[620975539] 'applied index is now lower than readState.Index' (duration: 9.002841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:33.680668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.204179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-25T01:19:33.680708Z","caller":"traceutil/trace.go:171","msg":"trace[1435911235] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:4991; }","duration":"555.305054ms","start":"2026-07-25T01:19:33.125392Z","end":"2026-07-25T01:19:33.680697Z","steps":["trace[1435911235] 'agreement among raft nodes before linearized reading' (duration: 555.129605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:33.68074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:33.125368Z","time spent":"555.359366ms","remote":"127.0.0.1:56400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-07-25T01:19:33.680746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.854092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T01:19:33.68097Z","caller":"traceutil/trace.go:171","msg":"trace[613806554] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4991; }","duration":"452.951808ms","start":"2026-07-25T01:19:33.227824Z","end":"2026-07-25T01:19:33.680776Z","steps":["trace[613806554] 'agreement among raft nodes before linearized reading' (duration: 452.879604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:33.681035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:33.227805Z","time spent":"453.19449ms","remote":"127.0.0.1:48834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-25T01:19:33.681226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.560828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:19:33.681257Z","caller":"traceutil/trace.go:171","msg":"trace[1843733070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4991; }","duration":"422.642962ms","start":"2026-07-25T01:19:33.258607Z","end":"2026-07-25T01:19:33.68125Z","steps":["trace[1843733070] 'agreement among raft nodes before linearized reading' (duration: 422.59716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:33.681278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T01:19:33.258584Z","time spent":"422.690424ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T01:19:33.891656Z","caller":"traceutil/trace.go:171","msg":"trace[2139496460] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"174.411387ms","start":"2026-07-25T01:19:33.717188Z","end":"2026-07-25T01:19:33.8916Z","steps":["trace[2139496460] 'process raft request' (duration: 147.672077ms)","trace[2139496460] 'compare' (duration: 26.253276ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:33.911737Z","caller":"traceutil/trace.go:171","msg":"trace[2117766161] linearizableReadLoop","detail":"{readStateIndex:5186; appliedIndex:5184; }","duration":"172.969477ms","start":"2026-07-25T01:19:33.738738Z","end":"2026-07-25T01:19:33.911707Z","steps":["trace[2117766161] 'read index received' (duration: 126.179954ms)","trace[2117766161] 'applied index is now lower than readState.Index' (duration: 46.788323ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:33.911753Z","caller":"traceutil/trace.go:171","msg":"trace[1291549530] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"192.00313ms","start":"2026-07-25T01:19:33.719725Z","end":"2026-07-25T01:19:33.911728Z","steps":["trace[1291549530] 'process raft request' (duration: 191.701395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:33.912478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.715203ms","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":"info","ts":"2026-07-25T01:19:33.912526Z","caller":"traceutil/trace.go:171","msg":"trace[2085031195] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:4995; }","duration":"173.824728ms","start":"2026-07-25T01:19:33.738688Z","end":"2026-07-25T01:19:33.912513Z","steps":["trace[2085031195] 'agreement among raft nodes before linearized reading' (duration: 173.723393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:33.923365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.219077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-25T01:19:33.923457Z","caller":"traceutil/trace.go:171","msg":"trace[285906199] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4996; }","duration":"175.324592ms","start":"2026-07-25T01:19:33.748104Z","end":"2026-07-25T01:19:33.923429Z","steps":["trace[285906199] 'agreement among raft nodes before linearized reading' (duration: 175.006227ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:34.046168Z","caller":"traceutil/trace.go:171","msg":"trace[1826655251] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"121.728337ms","start":"2026-07-25T01:19:33.924356Z","end":"2026-07-25T01:19:34.046084Z","steps":["trace[1826655251] 'process raft request' (duration: 97.721339ms)","trace[1826655251] 'compare' (duration: 23.687402ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:34.046194Z","caller":"traceutil/trace.go:171","msg":"trace[800715499] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"118.364872ms","start":"2026-07-25T01:19:33.927785Z","end":"2026-07-25T01:19:34.04615Z","steps":["trace[800715499] 'process raft request' (duration: 118.168823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:34.046574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.100645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-25T01:19:34.046499Z","caller":"traceutil/trace.go:171","msg":"trace[445816404] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"117.978713ms","start":"2026-07-25T01:19:33.92841Z","end":"2026-07-25T01:19:34.046389Z","steps":["trace[445816404] 'process raft request' (duration: 117.676978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:34.046668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.996018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-25T01:19:34.046705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.317341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-25T01:19:34.046748Z","caller":"traceutil/trace.go:171","msg":"trace[1777426208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5000; }","duration":"114.114974ms","start":"2026-07-25T01:19:33.932616Z","end":"2026-07-25T01:19:34.046731Z","steps":["trace[1777426208] 'agreement among raft nodes before linearized reading' (duration: 113.790748ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:34.046842Z","caller":"traceutil/trace.go:171","msg":"trace[619300182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5000; }","duration":"117.507661ms","start":"2026-07-25T01:19:33.929283Z","end":"2026-07-25T01:19:34.046757Z","steps":["trace[619300182] 'agreement among raft nodes before linearized reading' (duration: 117.183325ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:34.046689Z","caller":"traceutil/trace.go:171","msg":"trace[1142349753] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5000; }","duration":"102.231842ms","start":"2026-07-25T01:19:33.944421Z","end":"2026-07-25T01:19:34.046652Z","steps":["trace[1142349753] 'agreement among raft nodes before linearized reading' (duration: 101.923167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:34.047165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.758172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ca034809-6dff-438f-afbe-39dbfe641288\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-25T01:19:34.047308Z","caller":"traceutil/trace.go:171","msg":"trace[1834902334] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ca034809-6dff-438f-afbe-39dbfe641288; range_end:; response_count:1; response_revision:5000; }","duration":"118.905839ms","start":"2026-07-25T01:19:33.928352Z","end":"2026-07-25T01:19:34.047258Z","steps":["trace[1834902334] 'agreement among raft nodes before linearized reading' (duration: 118.234256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:34.047381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.433695ms","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-07-25T01:19:34.047453Z","caller":"traceutil/trace.go:171","msg":"trace[1943930966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5000; }","duration":"119.54231ms","start":"2026-07-25T01:19:33.927895Z","end":"2026-07-25T01:19:34.047437Z","steps":["trace[1943930966] 'agreement among raft nodes before linearized reading' (duration: 118.414295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:34.047542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.67509ms","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-07-25T01:19:34.047674Z","caller":"traceutil/trace.go:171","msg":"trace[1409239009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5000; }","duration":"106.867449ms","start":"2026-07-25T01:19:33.940789Z","end":"2026-07-25T01:19:34.047657Z","steps":["trace[1409239009] 'agreement among raft nodes before linearized reading' (duration: 106.452489ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:34.105456Z","caller":"traceutil/trace.go:171","msg":"trace[379831614] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"111.720609ms","start":"2026-07-25T01:19:33.993705Z","end":"2026-07-25T01:19:34.105426Z","steps":["trace[379831614] 'process raft request' (duration: 111.561712ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:34.185681Z","caller":"traceutil/trace.go:171","msg":"trace[1779325603] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"130.737953ms","start":"2026-07-25T01:19:34.054901Z","end":"2026-07-25T01:19:34.185639Z","steps":["trace[1779325603] 'process raft request' (duration: 130.444159ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:34.185717Z","caller":"traceutil/trace.go:171","msg":"trace[556810789] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5193; }","duration":"132.031196ms","start":"2026-07-25T01:19:34.053654Z","end":"2026-07-25T01:19:34.185685Z","steps":["trace[556810789] 'read index received' (duration: 51.832262ms)","trace[556810789] 'applied index is now lower than readState.Index' (duration: 80.197944ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:34.185821Z","caller":"traceutil/trace.go:171","msg":"trace[1553464529] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"130.809916ms","start":"2026-07-25T01:19:34.054994Z","end":"2026-07-25T01:19:34.185804Z","steps":["trace[1553464529] 'process raft request' (duration: 130.515222ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:34.185879Z","caller":"traceutil/trace.go:171","msg":"trace[762321160] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"130.784485ms","start":"2026-07-25T01:19:34.055079Z","end":"2026-07-25T01:19:34.185863Z","steps":["trace[762321160] 'process raft request' (duration: 130.528912ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:34.185962Z","caller":"traceutil/trace.go:171","msg":"trace[650396327] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"132.430926ms","start":"2026-07-25T01:19:34.05347Z","end":"2026-07-25T01:19:34.185901Z","steps":["trace[650396327] 'process raft request' (duration: 116.906714ms)","trace[650396327] 'compare' (duration: 14.712572ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:34.186032Z","caller":"traceutil/trace.go:171","msg":"trace[1668950927] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"130.784195ms","start":"2026-07-25T01:19:34.055234Z","end":"2026-07-25T01:19:34.186018Z","steps":["trace[1668950927] 'process raft request' (duration: 130.413106ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:34.185755Z","caller":"traceutil/trace.go:171","msg":"trace[1236498745] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"130.700531ms","start":"2026-07-25T01:19:34.055002Z","end":"2026-07-25T01:19:34.185702Z","steps":["trace[1236498745] 'process raft request' (duration: 130.559584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:34.186228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.547151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-25T01:19:34.186576Z","caller":"traceutil/trace.go:171","msg":"trace[1096124270] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5007; }","duration":"132.861036ms","start":"2026-07-25T01:19:34.053624Z","end":"2026-07-25T01:19:34.186485Z","steps":["trace[1096124270] 'agreement among raft nodes before linearized reading' (duration: 132.426675ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:19:34.442156Z","caller":"traceutil/trace.go:171","msg":"trace[293380205] linearizableReadLoop","detail":"{readStateIndex:5203; appliedIndex:5202; }","duration":"181.053511ms","start":"2026-07-25T01:19:34.261067Z","end":"2026-07-25T01:19:34.44212Z","steps":["trace[293380205] 'read index received' (duration: 162.182685ms)","trace[293380205] 'applied index is now lower than readState.Index' (duration: 18.869406ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T01:19:34.442187Z","caller":"traceutil/trace.go:171","msg":"trace[331168201] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"217.851825ms","start":"2026-07-25T01:19:34.224293Z","end":"2026-07-25T01:19:34.442145Z","steps":["trace[331168201] 'process raft request' (duration: 199.162248ms)","trace[331168201] 'compare' (duration: 18.487267ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T01:19:34.442366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.26482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:19:34.442437Z","caller":"traceutil/trace.go:171","msg":"trace[356123579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5011; }","duration":"181.400037ms","start":"2026-07-25T01:19:34.26102Z","end":"2026-07-25T01:19:34.44242Z","steps":["trace[356123579] 'agreement among raft nodes before linearized reading' (duration: 181.206478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T01:19:34.44257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.402301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T01:19:34.442615Z","caller":"traceutil/trace.go:171","msg":"trace[505120059] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5011; }","duration":"167.704665ms","start":"2026-07-25T01:19:34.274899Z","end":"2026-07-25T01:19:34.442603Z","steps":["trace[505120059] 'agreement among raft nodes before linearized reading' (duration: 167.39725ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T01:20:27.88472Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2812} {"level":"info","ts":"2026-07-25T01:20:27.960129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2812,"took":"73.747059ms","hash":633775844,"current-db-size-bytes":52531200,"current-db-size":"52 MB","current-db-size-in-use-bytes":28631040,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-25T01:20:27.960184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":633775844,"revision":2812,"compact-revision":-1} {"level":"info","ts":"2026-07-25T01:25:27.892449Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5435} {"level":"info","ts":"2026-07-25T01:25:27.992482Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5435,"took":"97.737646ms","hash":3116927926,"current-db-size-bytes":52531200,"current-db-size":"52 MB","current-db-size-in-use-bytes":18251776,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-25T01:25:27.99256Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3116927926,"revision":5435,"compact-revision":2812}