{"level":"warn","ts":"2026-07-16T14:15:08.277641Z","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-16T14:15:08.28061Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-16T14:15:08.280799Z","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-16T14:15:08.280826Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-16T14:15:08.280872Z","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-16T14:15:08.281901Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-07-16T14:15:08.28201Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-16T14:15:08.286118Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.79295ms"} {"level":"info","ts":"2026-07-16T14:15:08.296199Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-07-16T14:15:08.296293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T14:15:08.296322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-07-16T14:15:08.296334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T14:15:08.296363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-07-16T14:15:08.296408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-07-16T14:15:08.304318Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T14:15:08.306298Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T14:15:08.30792Z","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-16T14:15:08.31045Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T14:15:08.315545Z","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-16T14:15:08.315671Z","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-16T14:15:08.315695Z","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-16T14:15:08.315638Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T14:15:08.32007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-07-16T14:15:08.320205Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-16T14:15:08.321126Z","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-16T14:15:08.321218Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-16T14:15:08.321263Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-16T14:15:08.321361Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T14:15:08.321412Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T14:15:08.700529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T14:15:08.700583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T14:15:08.700601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-07-16T14:15:08.700614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-07-16T14:15:08.70062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-16T14:15:08.700632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-07-16T14:15:08.700641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-16T14:15:08.706764Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T14:15:08.713809Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T14:15:08.713999Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T14:15:08.715874Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-07-16T14:15:08.716171Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T14:15:08.7169Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T14:15:08.716943Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T14:15:08.717308Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T14:15:08.717473Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T14:15:08.717514Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T14:15:08.718974Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-16T14:15:34.555423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971871369202678,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T14:15:34.752171Z","caller":"traceutil/trace.go:171","msg":"trace[430967447] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"697.126595ms","start":"2026-07-16T14:15:34.055021Z","end":"2026-07-16T14:15:34.752148Z","steps":["trace[430967447] 'read index received' (duration: 696.946133ms)","trace[430967447] 'applied index is now lower than readState.Index' (duration: 179.352µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:15:34.752211Z","caller":"traceutil/trace.go:171","msg":"trace[1194443037] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"774.718266ms","start":"2026-07-16T14:15:33.977469Z","end":"2026-07-16T14:15:34.752187Z","steps":["trace[1194443037] 'process raft request' (duration: 774.546885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:34.752407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.546029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-16T14:15:34.752482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.378597ms","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-16T14:15:34.752587Z","caller":"traceutil/trace.go:171","msg":"trace[211639560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"697.571609ms","start":"2026-07-16T14:15:34.054981Z","end":"2026-07-16T14:15:34.752553Z","steps":["trace[211639560] 'agreement among raft nodes before linearized reading' (duration: 697.291827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:34.752636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:34.054965Z","time spent":"697.662109ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T14:15:34.752516Z","caller":"traceutil/trace.go:171","msg":"trace[895436386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"477.70613ms","start":"2026-07-16T14:15:34.274798Z","end":"2026-07-16T14:15:34.752504Z","steps":["trace[895436386] 'agreement among raft nodes before linearized reading' (duration: 477.488119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:34.752775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:34.274779Z","time spent":"477.965742ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T14:15:34.752815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:33.977442Z","time spent":"774.827127ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:15:35.039337Z","caller":"traceutil/trace.go:171","msg":"trace[630062661] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"283.198736ms","start":"2026-07-16T14:15:34.756105Z","end":"2026-07-16T14:15:35.039304Z","steps":["trace[630062661] 'process raft request' (duration: 183.466418ms)","trace[630062661] 'compare' (duration: 99.601667ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:15:35.203294Z","caller":"traceutil/trace.go:171","msg":"trace[719416824] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:476; }","duration":"378.29844ms","start":"2026-07-16T14:15:34.82496Z","end":"2026-07-16T14:15:35.203259Z","steps":["trace[719416824] 'read index received' (duration: 114.626326ms)","trace[719416824] 'applied index is now lower than readState.Index' (duration: 263.670534ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:15:35.203497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.502251ms","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-16T14:15:35.203496Z","caller":"traceutil/trace.go:171","msg":"trace[722710370] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"446.525546ms","start":"2026-07-16T14:15:34.75692Z","end":"2026-07-16T14:15:35.203445Z","steps":["trace[722710370] 'process raft request' (duration: 427.44102ms)","trace[722710370] 'compare' (duration: 18.737324ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:15:35.203551Z","caller":"traceutil/trace.go:171","msg":"trace[1403045555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:466; }","duration":"378.616292ms","start":"2026-07-16T14:15:34.82492Z","end":"2026-07-16T14:15:35.203536Z","steps":["trace[1403045555] 'agreement among raft nodes before linearized reading' (duration: 378.489141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:35.203605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:34.824903Z","time spent":"378.690922ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T14:15:35.203625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:34.756893Z","time spent":"446.659308ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:15:45.585722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971871369202807,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T14:15:45.824355Z","caller":"traceutil/trace.go:171","msg":"trace[1623246392] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"823.214976ms","start":"2026-07-16T14:15:45.001092Z","end":"2026-07-16T14:15:45.824307Z","steps":["trace[1623246392] 'process raft request' (duration: 821.103501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.824682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.001071Z","time spent":"823.349867ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:15:45.834938Z","caller":"traceutil/trace.go:171","msg":"trace[1383792525] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"833.363627ms","start":"2026-07-16T14:15:45.001554Z","end":"2026-07-16T14:15:45.834917Z","steps":["trace[1383792525] 'process raft request' (duration: 833.187776ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:15:45.83502Z","caller":"traceutil/trace.go:171","msg":"trace[385492243] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"830.87445ms","start":"2026-07-16T14:15:45.004119Z","end":"2026-07-16T14:15:45.834994Z","steps":["trace[385492243] 'process raft request' (duration: 830.664758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.83506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.001525Z","time spent":"833.469089ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:15:45.835076Z","caller":"traceutil/trace.go:171","msg":"trace[1065677658] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"817.474454ms","start":"2026-07-16T14:15:45.01758Z","end":"2026-07-16T14:15:45.835054Z","steps":["trace[1065677658] 'process raft request' (duration: 817.282313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.835131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.004109Z","time spent":"830.96444ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:15:45.834932Z","caller":"traceutil/trace.go:171","msg":"trace[1314916401] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"833.360578ms","start":"2026-07-16T14:15:45.001538Z","end":"2026-07-16T14:15:45.834899Z","steps":["trace[1314916401] 'process raft request' (duration: 833.092476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.835194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.017508Z","time spent":"817.635516ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T14:15:45.835185Z","caller":"traceutil/trace.go:171","msg":"trace[82617365] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"828.844716ms","start":"2026-07-16T14:15:45.006271Z","end":"2026-07-16T14:15:45.835116Z","steps":["trace[82617365] 'process raft request' (duration: 828.550404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.835254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.001489Z","time spent":"833.732781ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:15:45.835367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.006264Z","time spent":"829.010767ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:15:45.835385Z","caller":"traceutil/trace.go:171","msg":"trace[1251882721] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"817.729887ms","start":"2026-07-16T14:15:45.017644Z","end":"2026-07-16T14:15:45.835374Z","steps":["trace[1251882721] 'process raft request' (duration: 817.327204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.835501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.017618Z","time spent":"817.845617ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:15:45.835535Z","caller":"traceutil/trace.go:171","msg":"trace[107214865] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"820.357965ms","start":"2026-07-16T14:15:45.015122Z","end":"2026-07-16T14:15:45.83548Z","steps":["trace[107214865] 'process raft request' (duration: 820.116144ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:15:45.835604Z","caller":"traceutil/trace.go:171","msg":"trace[410286894] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:554; }","duration":"750.488468ms","start":"2026-07-16T14:15:45.085096Z","end":"2026-07-16T14:15:45.835584Z","steps":["trace[410286894] 'read index received' (duration: 737.165993ms)","trace[410286894] 'applied index is now lower than readState.Index' (duration: 13.321655ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:15:45.835674Z","caller":"traceutil/trace.go:171","msg":"trace[1098841433] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"713.143753ms","start":"2026-07-16T14:15:45.122521Z","end":"2026-07-16T14:15:45.835665Z","steps":["trace[1098841433] 'process raft request' (duration: 712.934231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.835759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.668389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-16T14:15:45.835773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.12249Z","time spent":"713.212063ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T14:15:45.835795Z","caller":"traceutil/trace.go:171","msg":"trace[592841644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"750.742751ms","start":"2026-07-16T14:15:45.085043Z","end":"2026-07-16T14:15:45.835786Z","steps":["trace[592841644] 'agreement among raft nodes before linearized reading' (duration: 750.63467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.835782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.015004Z","time spent":"820.621297ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:15:45.835823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.085021Z","time spent":"750.796491ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T14:15:45.845378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.847287ms","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-16T14:15:45.845432Z","caller":"traceutil/trace.go:171","msg":"trace[1631759256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"599.938117ms","start":"2026-07-16T14:15:45.245478Z","end":"2026-07-16T14:15:45.845416Z","steps":["trace[1631759256] 'agreement among raft nodes before linearized reading' (duration: 599.824936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.845467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.245458Z","time spent":"600.002857ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T14:15:45.84559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.924577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:15:45.845614Z","caller":"traceutil/trace.go:171","msg":"trace[2141041224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"197.946857ms","start":"2026-07-16T14:15:45.64766Z","end":"2026-07-16T14:15:45.845607Z","steps":["trace[2141041224] 'agreement among raft nodes before linearized reading' (duration: 197.912127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.845773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.032099ms","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-16T14:15:45.845838Z","caller":"traceutil/trace.go:171","msg":"trace[1624948114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"520.09464ms","start":"2026-07-16T14:15:45.325718Z","end":"2026-07-16T14:15:45.845812Z","steps":["trace[1624948114] 'agreement among raft nodes before linearized reading' (duration: 519.873958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:45.845913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:45.325655Z","time spent":"520.23736ms","remote":"127.0.0.1:55076","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-16T14:15:52.165799Z","caller":"traceutil/trace.go:171","msg":"trace[926253467] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"409.786614ms","start":"2026-07-16T14:15:51.755994Z","end":"2026-07-16T14:15:52.165781Z","steps":["trace[926253467] 'read index received' (duration: 409.16146ms)","trace[926253467] 'applied index is now lower than readState.Index' (duration: 624.334µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:15:52.165896Z","caller":"traceutil/trace.go:171","msg":"trace[336785480] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"526.880928ms","start":"2026-07-16T14:15:51.639009Z","end":"2026-07-16T14:15:52.16589Z","steps":["trace[336785480] 'process raft request' (duration: 526.359015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:52.165967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:51.638983Z","time spent":"526.928258ms","remote":"127.0.0.1:54906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:15:52.166229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.234077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-16T14:15:52.166252Z","caller":"traceutil/trace.go:171","msg":"trace[1872213585] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:628; }","duration":"410.298068ms","start":"2026-07-16T14:15:51.755949Z","end":"2026-07-16T14:15:52.166247Z","steps":["trace[1872213585] 'agreement among raft nodes before linearized reading' (duration: 410.253097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:52.166267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:51.755929Z","time spent":"410.335069ms","remote":"127.0.0.1:54814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-07-16T14:15:52.16632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.557777ms","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-16T14:15:52.166424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.283142ms","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-16T14:15:52.166417Z","caller":"traceutil/trace.go:171","msg":"trace[1035801173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"188.710119ms","start":"2026-07-16T14:15:51.977682Z","end":"2026-07-16T14:15:52.166393Z","steps":["trace[1035801173] 'agreement among raft nodes before linearized reading' (duration: 188.458277ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:15:52.166448Z","caller":"traceutil/trace.go:171","msg":"trace[1239143865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"291.319032ms","start":"2026-07-16T14:15:51.875124Z","end":"2026-07-16T14:15:52.166443Z","steps":["trace[1239143865] 'agreement among raft nodes before linearized reading' (duration: 291.249101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:52.166487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.981406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-16T14:15:52.16632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.185591ms","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-16T14:15:52.166563Z","caller":"traceutil/trace.go:171","msg":"trace[1137173455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"291.482604ms","start":"2026-07-16T14:15:51.875075Z","end":"2026-07-16T14:15:52.166558Z","steps":["trace[1137173455] 'agreement among raft nodes before linearized reading' (duration: 290.88515ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:15:52.166553Z","caller":"traceutil/trace.go:171","msg":"trace[592793777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"273.084107ms","start":"2026-07-16T14:15:51.893447Z","end":"2026-07-16T14:15:52.166531Z","steps":["trace[592793777] 'agreement among raft nodes before linearized reading' (duration: 272.925955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:53.398715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110393109s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-07-16T14:15:53.398984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:52.168148Z","time spent":"1.23082667s","remote":"127.0.0.1:54814","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-16T14:15:53.399061Z","caller":"traceutil/trace.go:171","msg":"trace[437746677] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:644; }","duration":"748.323549ms","start":"2026-07-16T14:15:52.650722Z","end":"2026-07-16T14:15:53.399045Z","steps":["trace[437746677] 'read index received' (duration: 42.49µs)","trace[437746677] 'applied index is now lower than readState.Index' (duration: 748.280229ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:15:53.399154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.4313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:15:53.399158Z","caller":"traceutil/trace.go:171","msg":"trace[698979410] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.227823419s","start":"2026-07-16T14:15:52.171308Z","end":"2026-07-16T14:15:53.399131Z","steps":["trace[698979410] 'process raft request' (duration: 1.227488407s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:15:53.399187Z","caller":"traceutil/trace.go:171","msg":"trace[1832195050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"748.5042ms","start":"2026-07-16T14:15:52.650672Z","end":"2026-07-16T14:15:53.399176Z","steps":["trace[1832195050] 'agreement among raft nodes before linearized reading' (duration: 748.429439ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:15:53.39921Z","caller":"traceutil/trace.go:171","msg":"trace[2019081581] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.227592458s","start":"2026-07-16T14:15:52.171607Z","end":"2026-07-16T14:15:53.3992Z","steps":["trace[2019081581] 'process raft request' (duration: 1.227305426s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:53.399223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:52.650654Z","time spent":"748.56254ms","remote":"127.0.0.1:54792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:15:53.399279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:52.171263Z","time spent":"1.22794184s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:15:53.399264Z","caller":"traceutil/trace.go:171","msg":"trace[2008665121] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.226633631s","start":"2026-07-16T14:15:52.172599Z","end":"2026-07-16T14:15:53.399233Z","steps":["trace[2008665121] 'process raft request' (duration: 1.226378949s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:15:53.399143Z","caller":"traceutil/trace.go:171","msg":"trace[1066843147] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.226615641s","start":"2026-07-16T14:15:52.172492Z","end":"2026-07-16T14:15:53.399108Z","steps":["trace[1066843147] 'process raft request' (duration: 1.22645615s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:53.399339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:52.171595Z","time spent":"1.227664378s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:15:53.399395Z","caller":"traceutil/trace.go:171","msg":"trace[1368431551] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.175646113s","start":"2026-07-16T14:15:52.223739Z","end":"2026-07-16T14:15:53.399385Z","steps":["trace[1368431551] 'process raft request' (duration: 1.175276401s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:53.3994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:52.172586Z","time spent":"1.226744342s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:15:53.399405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:52.172471Z","time spent":"1.226880703s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:15:53.399461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:52.22372Z","time spent":"1.175706573s","remote":"127.0.0.1:55316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T14:15:55.006373Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.177955784s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T14:15:55.009475Z","caller":"traceutil/trace.go:171","msg":"trace[2128103553] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.182468235s","start":"2026-07-16T14:15:53.82698Z","end":"2026-07-16T14:15:55.009448Z","steps":["trace[2128103553] 'process raft request' (duration: 1.179742627s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:15:55.009593Z","caller":"traceutil/trace.go:171","msg":"trace[1681741888] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"163.05667ms","start":"2026-07-16T14:15:54.846512Z","end":"2026-07-16T14:15:55.009568Z","steps":["trace[1681741888] 'process raft request' (duration: 162.956409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:55.009615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:53.826955Z","time spent":"1.182585556s","remote":"127.0.0.1:54906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:15:55.00971Z","caller":"traceutil/trace.go:171","msg":"trace[1749758165] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"359.409845ms","start":"2026-07-16T14:15:54.65028Z","end":"2026-07-16T14:15:55.00969Z","steps":["trace[1749758165] 'read index received' (duration: 356.274594ms)","trace[1749758165] 'applied index is now lower than readState.Index' (duration: 3.133081ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:15:55.009799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.509775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:15:55.009829Z","caller":"traceutil/trace.go:171","msg":"trace[532050318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"359.592286ms","start":"2026-07-16T14:15:54.65023Z","end":"2026-07-16T14:15:55.009823Z","steps":["trace[532050318] 'agreement among raft nodes before linearized reading' (duration: 359.523335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:15:55.009852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:15:54.65021Z","time spent":"359.636926ms","remote":"127.0.0.1:54792","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-16T14:15:56.921661Z","caller":"traceutil/trace.go:171","msg":"trace[1625765098] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"123.801217ms","start":"2026-07-16T14:15:56.797838Z","end":"2026-07-16T14:15:56.921639Z","steps":["trace[1625765098] 'process raft request' (duration: 123.674456ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:16:10.222273Z","caller":"traceutil/trace.go:171","msg":"trace[599310968] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"541.867286ms","start":"2026-07-16T14:16:09.680381Z","end":"2026-07-16T14:16:10.222248Z","steps":["trace[599310968] 'process raft request' (duration: 541.740851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:16:10.222468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:16:09.680368Z","time spent":"542.012152ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:16:10.970659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.231664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:16:10.970717Z","caller":"traceutil/trace.go:171","msg":"trace[1951784158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"321.343048ms","start":"2026-07-16T14:16:10.649361Z","end":"2026-07-16T14:16:10.970704Z","steps":["trace[1951784158] 'range keys from in-memory index tree' (duration: 321.12023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:16:10.97074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:16:10.649334Z","time spent":"321.40076ms","remote":"127.0.0.1:54792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:16:10.970768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.303444ms","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-16T14:16:10.970837Z","caller":"traceutil/trace.go:171","msg":"trace[1364331281] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"516.386017ms","start":"2026-07-16T14:16:10.454433Z","end":"2026-07-16T14:16:10.970819Z","steps":["trace[1364331281] 'range keys from in-memory index tree' (duration: 516.034234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:16:10.970877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:16:10.454365Z","time spent":"516.503782ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-16T14:16:45.298259Z","caller":"traceutil/trace.go:171","msg":"trace[929057992] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"142.5945ms","start":"2026-07-16T14:16:45.155634Z","end":"2026-07-16T14:16:45.298229Z","steps":["trace[929057992] 'process raft request' (duration: 142.449626ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:09.537669Z","caller":"traceutil/trace.go:171","msg":"trace[1748559545] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"103.037702ms","start":"2026-07-16T14:17:09.434606Z","end":"2026-07-16T14:17:09.537644Z","steps":["trace[1748559545] 'process raft request' (duration: 102.897159ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:26.003337Z","caller":"traceutil/trace.go:171","msg":"trace[330143733] linearizableReadLoop","detail":"{readStateIndex:1806; appliedIndex:1805; }","duration":"127.765252ms","start":"2026-07-16T14:17:25.875543Z","end":"2026-07-16T14:17:26.003308Z","steps":["trace[330143733] 'read index received' (duration: 127.64401ms)","trace[330143733] 'applied index is now lower than readState.Index' (duration: 119.822µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:26.003404Z","caller":"traceutil/trace.go:171","msg":"trace[2023220839] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"179.822568ms","start":"2026-07-16T14:17:25.823523Z","end":"2026-07-16T14:17:26.003346Z","steps":["trace[2023220839] 'process raft request' (duration: 179.660345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:26.003587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.966616ms","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-16T14:17:26.003632Z","caller":"traceutil/trace.go:171","msg":"trace[950835703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1741; }","duration":"128.064837ms","start":"2026-07-16T14:17:25.875556Z","end":"2026-07-16T14:17:26.003621Z","steps":["trace[950835703] 'agreement among raft nodes before linearized reading' (duration: 127.914314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:26.003789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.068258ms","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-16T14:17:26.004531Z","caller":"traceutil/trace.go:171","msg":"trace[2060812117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1741; }","duration":"128.357343ms","start":"2026-07-16T14:17:25.875512Z","end":"2026-07-16T14:17:26.003869Z","steps":["trace[2060812117] 'agreement among raft nodes before linearized reading' (duration: 127.903634ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.557079Z","caller":"traceutil/trace.go:171","msg":"trace[1683030774] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1967; }","duration":"139.132211ms","start":"2026-07-16T14:17:47.417931Z","end":"2026-07-16T14:17:47.557063Z","steps":["trace[1683030774] 'read index received' (duration: 92.752791ms)","trace[1683030774] 'applied index is now lower than readState.Index' (duration: 46.37866ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:47.557174Z","caller":"traceutil/trace.go:171","msg":"trace[464721461] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"140.528983ms","start":"2026-07-16T14:17:47.416635Z","end":"2026-07-16T14:17:47.557164Z","steps":["trace[464721461] 'process raft request' (duration: 140.399021ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.557145Z","caller":"traceutil/trace.go:171","msg":"trace[152616318] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"144.770738ms","start":"2026-07-16T14:17:47.41233Z","end":"2026-07-16T14:17:47.557101Z","steps":["trace[152616318] 'process raft request' (duration: 98.340197ms)","trace[152616318] 'compare' (duration: 46.251318ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:47.557512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.94704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9247"} {"level":"info","ts":"2026-07-16T14:17:47.557532Z","caller":"traceutil/trace.go:171","msg":"trace[381441984] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"135.238421ms","start":"2026-07-16T14:17:47.422285Z","end":"2026-07-16T14:17:47.557523Z","steps":["trace[381441984] 'process raft request' (duration: 134.856335ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.557584Z","caller":"traceutil/trace.go:171","msg":"trace[1169327937] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1900; }","duration":"143.069302ms","start":"2026-07-16T14:17:47.414502Z","end":"2026-07-16T14:17:47.557571Z","steps":["trace[1169327937] 'agreement among raft nodes before linearized reading' (duration: 142.842348ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.557583Z","caller":"traceutil/trace.go:171","msg":"trace[1299776443] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"134.282346ms","start":"2026-07-16T14:17:47.423259Z","end":"2026-07-16T14:17:47.557541Z","steps":["trace[1299776443] 'process raft request' (duration: 134.146854ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.557751Z","caller":"traceutil/trace.go:171","msg":"trace[1046991379] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"129.92654ms","start":"2026-07-16T14:17:47.427813Z","end":"2026-07-16T14:17:47.55774Z","steps":["trace[1046991379] 'process raft request' (duration: 129.670897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:47.557797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.105261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" ","response":"range_response_count:1 size:18093"} {"level":"info","ts":"2026-07-16T14:17:47.557838Z","caller":"traceutil/trace.go:171","msg":"trace[1698112643] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"135.536026ms","start":"2026-07-16T14:17:47.422291Z","end":"2026-07-16T14:17:47.557827Z","steps":["trace[1698112643] 'process raft request' (duration: 135.080709ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.55792Z","caller":"traceutil/trace.go:171","msg":"trace[1888706331] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"134.553711ms","start":"2026-07-16T14:17:47.423359Z","end":"2026-07-16T14:17:47.557913Z","steps":["trace[1888706331] 'process raft request' (duration: 134.087684ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.557897Z","caller":"traceutil/trace.go:171","msg":"trace[1253136207] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-pxc; range_end:; response_count:1; response_revision:1905; }","duration":"126.234814ms","start":"2026-07-16T14:17:47.431648Z","end":"2026-07-16T14:17:47.557882Z","steps":["trace[1253136207] 'agreement among raft nodes before linearized reading' (duration: 125.939939ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.558143Z","caller":"traceutil/trace.go:171","msg":"trace[756557143] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"128.684361ms","start":"2026-07-16T14:17:47.429438Z","end":"2026-07-16T14:17:47.558123Z","steps":["trace[756557143] 'process raft request' (duration: 128.084992ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.703958Z","caller":"traceutil/trace.go:171","msg":"trace[277090932] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"136.735535ms","start":"2026-07-16T14:17:47.567188Z","end":"2026-07-16T14:17:47.703924Z","steps":["trace[277090932] 'process raft request' (duration: 103.873791ms)","trace[277090932] 'compare' (duration: 32.549779ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:47.706975Z","caller":"traceutil/trace.go:171","msg":"trace[1094716594] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"139.488227ms","start":"2026-07-16T14:17:47.567444Z","end":"2026-07-16T14:17:47.706932Z","steps":["trace[1094716594] 'process raft request' (duration: 139.279854ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.707034Z","caller":"traceutil/trace.go:171","msg":"trace[1713559707] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"139.350185ms","start":"2026-07-16T14:17:47.56765Z","end":"2026-07-16T14:17:47.707Z","steps":["trace[1713559707] 'process raft request' (duration: 139.209513ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.707315Z","caller":"traceutil/trace.go:171","msg":"trace[770396253] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"137.76812ms","start":"2026-07-16T14:17:47.569531Z","end":"2026-07-16T14:17:47.707299Z","steps":["trace[770396253] 'process raft request' (duration: 137.583988ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.707341Z","caller":"traceutil/trace.go:171","msg":"trace[886746243] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1975; }","duration":"134.306938ms","start":"2026-07-16T14:17:47.573005Z","end":"2026-07-16T14:17:47.707312Z","steps":["trace[886746243] 'read index received' (duration: 98.072863ms)","trace[886746243] 'applied index is now lower than readState.Index' (duration: 36.233375ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:47.707478Z","caller":"traceutil/trace.go:171","msg":"trace[1423304178] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"134.931827ms","start":"2026-07-16T14:17:47.572537Z","end":"2026-07-16T14:17:47.707469Z","steps":["trace[1423304178] 'process raft request' (duration: 134.683683ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.70769Z","caller":"traceutil/trace.go:171","msg":"trace[1141427530] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"130.737152ms","start":"2026-07-16T14:17:47.576944Z","end":"2026-07-16T14:17:47.707681Z","steps":["trace[1141427530] 'process raft request' (duration: 130.324356ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:47.707719Z","caller":"traceutil/trace.go:171","msg":"trace[481528889] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"139.7369ms","start":"2026-07-16T14:17:47.567947Z","end":"2026-07-16T14:17:47.707684Z","steps":["trace[481528889] 'process raft request' (duration: 138.966908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:47.707802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.782075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:47.707831Z","caller":"traceutil/trace.go:171","msg":"trace[2024259992] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1912; }","duration":"134.844975ms","start":"2026-07-16T14:17:47.572977Z","end":"2026-07-16T14:17:47.707822Z","steps":["trace[2024259992] 'agreement among raft nodes before linearized reading' (duration: 134.788514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:47.708358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.964104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-07-16T14:17:47.708429Z","caller":"traceutil/trace.go:171","msg":"trace[301880377] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1912; }","duration":"125.234098ms","start":"2026-07-16T14:17:47.583176Z","end":"2026-07-16T14:17:47.70841Z","steps":["trace[301880377] 'agreement among raft nodes before linearized reading' (duration: 124.858783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:50.253081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971871369205682,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T14:17:50.510302Z","caller":"traceutil/trace.go:171","msg":"trace[2027750313] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"759.364656ms","start":"2026-07-16T14:17:49.750916Z","end":"2026-07-16T14:17:50.51028Z","steps":["trace[2027750313] 'process raft request' (duration: 759.210193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:50.510445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:49.750901Z","time spent":"759.466538ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:50.933252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.04936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:50.933411Z","caller":"traceutil/trace.go:171","msg":"trace[151476245] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"1.023909111s","start":"2026-07-16T14:17:49.909482Z","end":"2026-07-16T14:17:50.933391Z","steps":["trace[151476245] 'process raft request' (duration: 813.655219ms)","trace[151476245] 'compare' (duration: 209.900597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:50.933481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:49.909459Z","time spent":"1.023992312s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:50.933708Z","caller":"traceutil/trace.go:171","msg":"trace[812756758] linearizableReadLoop","detail":"{readStateIndex:2036; appliedIndex:2034; }","duration":"1.181340944s","start":"2026-07-16T14:17:49.752343Z","end":"2026-07-16T14:17:50.933684Z","steps":["trace[812756758] 'read index received' (duration: 757.775342ms)","trace[812756758] 'applied index is now lower than readState.Index' (duration: 423.564182ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:50.933735Z","caller":"traceutil/trace.go:171","msg":"trace[189482479] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"604.751397ms","start":"2026-07-16T14:17:50.328964Z","end":"2026-07-16T14:17:50.933716Z","steps":["trace[189482479] 'process raft request' (duration: 604.380642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:50.933833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:50.328938Z","time spent":"604.837468ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:50.933895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181543666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-839aa3a4-b2cf-412c-a417-1ca9688db025\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-16T14:17:50.933926Z","caller":"traceutil/trace.go:171","msg":"trace[1371171962] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-839aa3a4-b2cf-412c-a417-1ca9688db025; range_end:; response_count:1; response_revision:1967; }","duration":"1.181626428s","start":"2026-07-16T14:17:49.75229Z","end":"2026-07-16T14:17:50.933917Z","steps":["trace[1371171962] 'agreement among raft nodes before linearized reading' (duration: 1.181485546s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:50.93395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:49.752271Z","time spent":"1.181673259s","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-839aa3a4-b2cf-412c-a417-1ca9688db025\" "} {"level":"warn","ts":"2026-07-16T14:17:51.434588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971871369205688,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T14:17:52.072614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.648963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:52.072883Z","caller":"traceutil/trace.go:171","msg":"trace[176956861] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2037; }","duration":"1.139110993s","start":"2026-07-16T14:17:50.933755Z","end":"2026-07-16T14:17:52.072866Z","steps":["trace[176956861] 'read index received' (duration: 797.130526ms)","trace[176956861] 'applied index is now lower than readState.Index' (duration: 341.979427ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:52.07325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.425128916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T14:17:52.073332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473227999s","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-16T14:17:52.073369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97852949s","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":"warn","ts":"2026-07-16T14:17:52.073383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.024889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-16T14:17:52.073386Z","caller":"traceutil/trace.go:171","msg":"trace[597816726] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"494.447878ms","start":"2026-07-16T14:17:51.578927Z","end":"2026-07-16T14:17:52.073375Z","steps":["trace[597816726] 'process raft request' (duration: 493.896229ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:52.073376Z","caller":"traceutil/trace.go:171","msg":"trace[1911259269] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"1.273490825s","start":"2026-07-16T14:17:50.799858Z","end":"2026-07-16T14:17:52.073349Z","steps":["trace[1911259269] 'process raft request' (duration: 1.272859376s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:52.073416Z","caller":"traceutil/trace.go:171","msg":"trace[1424978673] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1970; }","duration":"1.978605172s","start":"2026-07-16T14:17:50.094797Z","end":"2026-07-16T14:17:52.073402Z","steps":["trace[1424978673] 'agreement among raft nodes before linearized reading' (duration: 1.978512171s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:52.073415Z","caller":"traceutil/trace.go:171","msg":"trace[1370594641] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:1970; }","duration":"310.09154ms","start":"2026-07-16T14:17:51.763313Z","end":"2026-07-16T14:17:52.073405Z","steps":["trace[1370594641] 'agreement among raft nodes before linearized reading' (duration: 310.006279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:52.07345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:51.763293Z","time spent":"310.151422ms","remote":"127.0.0.1:54814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-07-16T14:17:52.073473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.843995517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T14:17:52.073485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:51.57887Z","time spent":"494.550069ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:52.073489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.841465888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T14:17:52.0735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:50.799833Z","time spent":"1.273610827s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:52.073507Z","caller":"traceutil/trace.go:171","msg":"trace[1208699237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1970; }","duration":"1.844065167s","start":"2026-07-16T14:17:50.229434Z","end":"2026-07-16T14:17:52.073499Z","steps":["trace[1208699237] 'agreement among raft nodes before linearized reading' (duration: 1.843983356s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:52.073539Z","caller":"traceutil/trace.go:171","msg":"trace[1352689658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1970; }","duration":"1.84155889s","start":"2026-07-16T14:17:50.231969Z","end":"2026-07-16T14:17:52.073528Z","steps":["trace[1352689658] 'agreement among raft nodes before linearized reading' (duration: 1.841392427s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:52.073552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:50.229412Z","time spent":"1.844132019s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T14:17:52.073574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:50.231916Z","time spent":"1.841653591s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T14:17:52.073337Z","caller":"traceutil/trace.go:171","msg":"trace[1788141366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1970; }","duration":"1.425259958s","start":"2026-07-16T14:17:50.648057Z","end":"2026-07-16T14:17:52.073317Z","steps":["trace[1788141366] 'agreement among raft nodes before linearized reading' (duration: 1.425114595s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:52.073281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.962243465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-839aa3a4-b2cf-412c-a417-1ca9688db025\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-16T14:17:52.073625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:50.648037Z","time spent":"1.425580042s","remote":"127.0.0.1:54792","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-16T14:17:52.073641Z","caller":"traceutil/trace.go:171","msg":"trace[1678250856] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-839aa3a4-b2cf-412c-a417-1ca9688db025; range_end:; response_count:1; response_revision:1970; }","duration":"1.962645901s","start":"2026-07-16T14:17:50.110984Z","end":"2026-07-16T14:17:52.07363Z","steps":["trace[1678250856] 'agreement among raft nodes before linearized reading' (duration: 1.962151334s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:52.073457Z","caller":"traceutil/trace.go:171","msg":"trace[1810379691] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"1.373002921s","start":"2026-07-16T14:17:50.700356Z","end":"2026-07-16T14:17:52.073359Z","steps":["trace[1810379691] 'process raft request' (duration: 1.030515236s)","trace[1810379691] 'compare' (duration: 340.859271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:52.073258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.888181001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T14:17:52.073721Z","caller":"traceutil/trace.go:171","msg":"trace[1350380906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1970; }","duration":"1.888685249s","start":"2026-07-16T14:17:50.185027Z","end":"2026-07-16T14:17:52.073712Z","steps":["trace[1350380906] 'agreement among raft nodes before linearized reading' (duration: 1.88809005s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:52.073758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.131147661s","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-16T14:17:52.073796Z","caller":"traceutil/trace.go:171","msg":"trace[1080220538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1970; }","duration":"2.131233473s","start":"2026-07-16T14:17:49.942553Z","end":"2026-07-16T14:17:52.073786Z","steps":["trace[1080220538] 'agreement among raft nodes before linearized reading' (duration: 2.131155521s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:52.07339Z","caller":"traceutil/trace.go:171","msg":"trace[641542612] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1970; }","duration":"1.47331303s","start":"2026-07-16T14:17:50.600058Z","end":"2026-07-16T14:17:52.073371Z","steps":["trace[641542612] 'agreement among raft nodes before linearized reading' (duration: 1.473247619s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:52.073826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:49.942533Z","time spent":"2.131286313s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T14:17:52.073851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:50.600032Z","time spent":"1.473801827s","remote":"127.0.0.1:54142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-16T14:17:52.073757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:50.18501Z","time spent":"1.8887396s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T14:17:52.073933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:50.700332Z","time spent":"1.373406088s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:52.073688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.105326461s","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":"warn","ts":"2026-07-16T14:17:52.073995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.809675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-839aa3a4-b2cf-412c-a417-1ca9688db025\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-16T14:17:52.074016Z","caller":"traceutil/trace.go:171","msg":"trace[1088306044] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-839aa3a4-b2cf-412c-a417-1ca9688db025; range_end:; response_count:1; response_revision:1970; }","duration":"135.860325ms","start":"2026-07-16T14:17:51.93815Z","end":"2026-07-16T14:17:52.07401Z","steps":["trace[1088306044] 'agreement among raft nodes before linearized reading' (duration: 135.808284ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:52.074024Z","caller":"traceutil/trace.go:171","msg":"trace[423959262] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1970; }","duration":"2.105679906s","start":"2026-07-16T14:17:49.968328Z","end":"2026-07-16T14:17:52.074008Z","steps":["trace[423959262] 'agreement among raft nodes before linearized reading' (duration: 2.10528461s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:52.073454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:50.094778Z","time spent":"1.978667143s","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-16T14:17:52.074072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:49.968315Z","time spent":"2.105744348s","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-16T14:17:52.07368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:50.110953Z","time spent":"1.962717302s","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-839aa3a4-b2cf-412c-a417-1ca9688db025\" "} {"level":"info","ts":"2026-07-16T14:17:56.743925Z","caller":"traceutil/trace.go:171","msg":"trace[1933405619] linearizableReadLoop","detail":"{readStateIndex:2100; appliedIndex:2099; }","duration":"108.181763ms","start":"2026-07-16T14:17:56.635727Z","end":"2026-07-16T14:17:56.743909Z","steps":["trace[1933405619] 'read index received' (duration: 108.02275ms)","trace[1933405619] 'applied index is now lower than readState.Index' (duration: 158.473µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:56.744011Z","caller":"traceutil/trace.go:171","msg":"trace[1473801863] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"208.009492ms","start":"2026-07-16T14:17:56.535973Z","end":"2026-07-16T14:17:56.743983Z","steps":["trace[1473801863] 'process raft request' (duration: 207.822749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:56.744171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.397286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:56.744275Z","caller":"traceutil/trace.go:171","msg":"trace[1838414678] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2029; }","duration":"108.566728ms","start":"2026-07-16T14:17:56.635685Z","end":"2026-07-16T14:17:56.744252Z","steps":["trace[1838414678] 'agreement among raft nodes before linearized reading' (duration: 108.334464ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:57.168943Z","caller":"traceutil/trace.go:171","msg":"trace[1318372522] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"217.615628ms","start":"2026-07-16T14:17:56.951298Z","end":"2026-07-16T14:17:57.168913Z","steps":["trace[1318372522] 'process raft request' (duration: 217.433236ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:57.170046Z","caller":"traceutil/trace.go:171","msg":"trace[10851671] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"218.117846ms","start":"2026-07-16T14:17:56.951883Z","end":"2026-07-16T14:17:57.170001Z","steps":["trace[10851671] 'process raft request' (duration: 217.856122ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:57.1716Z","caller":"traceutil/trace.go:171","msg":"trace[1012053169] linearizableReadLoop","detail":"{readStateIndex:2102; appliedIndex:2100; }","duration":"190.238291ms","start":"2026-07-16T14:17:56.981345Z","end":"2026-07-16T14:17:57.171583Z","steps":["trace[1012053169] 'read index received' (duration: 187.446271ms)","trace[1012053169] 'applied index is now lower than readState.Index' (duration: 2.79096ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:57.171732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.376273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:57.171762Z","caller":"traceutil/trace.go:171","msg":"trace[696756085] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2031; }","duration":"190.462383ms","start":"2026-07-16T14:17:56.98129Z","end":"2026-07-16T14:17:57.171753Z","steps":["trace[696756085] 'agreement among raft nodes before linearized reading' (duration: 190.396402ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:58.373273Z","caller":"traceutil/trace.go:171","msg":"trace[1081940701] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"277.463447ms","start":"2026-07-16T14:17:58.095785Z","end":"2026-07-16T14:17:58.373249Z","steps":["trace[1081940701] 'process raft request' (duration: 277.306685ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:58.390166Z","caller":"traceutil/trace.go:171","msg":"trace[141541574] linearizableReadLoop","detail":"{readStateIndex:2106; appliedIndex:2104; }","duration":"258.735308ms","start":"2026-07-16T14:17:58.131402Z","end":"2026-07-16T14:17:58.390138Z","steps":["trace[141541574] 'read index received' (duration: 241.724283ms)","trace[141541574] 'applied index is now lower than readState.Index' (duration: 17.009625ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:58.3903Z","caller":"traceutil/trace.go:171","msg":"trace[1418689399] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"292.25048ms","start":"2026-07-16T14:17:58.097998Z","end":"2026-07-16T14:17:58.390248Z","steps":["trace[1418689399] 'process raft request' (duration: 291.961656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:58.390447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.033293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T14:17:58.390491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.17077ms","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-16T14:17:58.390514Z","caller":"traceutil/trace.go:171","msg":"trace[238095044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2034; }","duration":"259.146354ms","start":"2026-07-16T14:17:58.13135Z","end":"2026-07-16T14:17:58.390497Z","steps":["trace[238095044] 'agreement among raft nodes before linearized reading' (duration: 258.935241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:58.390518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.609588ms","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-16T14:17:58.390543Z","caller":"traceutil/trace.go:171","msg":"trace[1314402272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2034; }","duration":"258.298602ms","start":"2026-07-16T14:17:58.132229Z","end":"2026-07-16T14:17:58.390528Z","steps":["trace[1314402272] 'agreement among raft nodes before linearized reading' (duration: 258.089679ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:58.390568Z","caller":"traceutil/trace.go:171","msg":"trace[895990916] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2034; }","duration":"208.679799ms","start":"2026-07-16T14:17:58.181871Z","end":"2026-07-16T14:17:58.390551Z","steps":["trace[895990916] 'agreement among raft nodes before linearized reading' (duration: 208.554897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:58.390648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.337848ms","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-16T14:17:58.390689Z","caller":"traceutil/trace.go:171","msg":"trace[950249675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2034; }","duration":"257.419089ms","start":"2026-07-16T14:17:58.133259Z","end":"2026-07-16T14:17:58.390679Z","steps":["trace[950249675] 'agreement among raft nodes before linearized reading' (duration: 257.280337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:58.390744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.270737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T14:17:58.390816Z","caller":"traceutil/trace.go:171","msg":"trace[1457344610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2034; }","duration":"257.399379ms","start":"2026-07-16T14:17:58.1334Z","end":"2026-07-16T14:17:58.390799Z","steps":["trace[1457344610] 'agreement among raft nodes before linearized reading' (duration: 257.132095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.124536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700399939s","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-16T14:18:05.12461Z","caller":"traceutil/trace.go:171","msg":"trace[795764996] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2067; }","duration":"1.7005282s","start":"2026-07-16T14:18:03.424064Z","end":"2026-07-16T14:18:05.124592Z","steps":["trace[795764996] 'range keys from in-memory index tree' (duration: 1.700198775s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.124654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:03.424041Z","time spent":"1.70060337s","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-16T14:18:05.124887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.89855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:18:05.125065Z","caller":"traceutil/trace.go:171","msg":"trace[40937377] linearizableReadLoop","detail":"{readStateIndex:2142; appliedIndex:2140; }","duration":"970.797503ms","start":"2026-07-16T14:18:04.154252Z","end":"2026-07-16T14:18:05.125049Z","steps":["trace[40937377] 'read index received' (duration: 36.6804ms)","trace[40937377] 'applied index is now lower than readState.Index' (duration: 934.116213ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:18:05.125143Z","caller":"traceutil/trace.go:171","msg":"trace[103453355] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"1.12865555s","start":"2026-07-16T14:18:03.996477Z","end":"2026-07-16T14:18:05.125133Z","steps":["trace[103453355] 'process raft request' (duration: 1.128506358s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.125203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:03.996433Z","time spent":"1.128739601s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:05.125429Z","caller":"traceutil/trace.go:171","msg":"trace[256467611] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"1.266765143s","start":"2026-07-16T14:18:03.858333Z","end":"2026-07-16T14:18:05.125098Z","steps":["trace[256467611] 'process raft request' (duration: 332.581519ms)","trace[256467611] 'compare' (duration: 933.402113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:05.125626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.372381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:18:05.125669Z","caller":"traceutil/trace.go:171","msg":"trace[778484893] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2069; }","duration":"971.445912ms","start":"2026-07-16T14:18:04.154213Z","end":"2026-07-16T14:18:05.125659Z","steps":["trace[778484893] 'agreement among raft nodes before linearized reading' (duration: 971.358451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.125701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:04.1542Z","time spent":"971.492893ms","remote":"127.0.0.1:49084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-16T14:18:05.125697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:03.858305Z","time spent":"1.267207828s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:05.136066Z","caller":"traceutil/trace.go:171","msg":"trace[501872559] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"739.96615ms","start":"2026-07-16T14:18:04.396082Z","end":"2026-07-16T14:18:05.136048Z","steps":["trace[501872559] 'process raft request' (duration: 739.838608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.136168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:04.396052Z","time spent":"740.064421ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:05.136292Z","caller":"traceutil/trace.go:171","msg":"trace[1668370636] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"725.146384ms","start":"2026-07-16T14:18:04.411128Z","end":"2026-07-16T14:18:05.136274Z","steps":["trace[1668370636] 'process raft request' (duration: 724.88084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.136593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.033423ms","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-16T14:18:05.136621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.200354ms","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-16T14:18:05.136678Z","caller":"traceutil/trace.go:171","msg":"trace[501866150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2071; }","duration":"651.284255ms","start":"2026-07-16T14:18:04.485379Z","end":"2026-07-16T14:18:05.136664Z","steps":["trace[501866150] 'agreement among raft nodes before linearized reading' (duration: 651.137203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.136714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:04.48537Z","time spent":"651.337316ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T14:18:05.13672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.235025ms","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-16T14:18:05.136818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.361461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-16T14:18:05.136815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.801716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:18:05.13685Z","caller":"traceutil/trace.go:171","msg":"trace[1835678325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2071; }","duration":"665.410812ms","start":"2026-07-16T14:18:04.471432Z","end":"2026-07-16T14:18:05.136843Z","steps":["trace[1835678325] 'agreement among raft nodes before linearized reading' (duration: 665.33342ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:05.136867Z","caller":"traceutil/trace.go:171","msg":"trace[1555168777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2071; }","duration":"486.901698ms","start":"2026-07-16T14:18:04.649953Z","end":"2026-07-16T14:18:05.136855Z","steps":["trace[1555168777] 'agreement among raft nodes before linearized reading' (duration: 486.799026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.136883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:04.471423Z","time spent":"665.452553ms","remote":"127.0.0.1:55076","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-16T14:18:05.136818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:04.411104Z","time spent":"725.255205ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:05.136921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:04.649936Z","time spent":"486.974849ms","remote":"127.0.0.1:54792","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-16T14:18:05.13664Z","caller":"traceutil/trace.go:171","msg":"trace[612373995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2071; }","duration":"669.101584ms","start":"2026-07-16T14:18:04.467526Z","end":"2026-07-16T14:18:05.136627Z","steps":["trace[612373995] 'agreement among raft nodes before linearized reading' (duration: 668.965742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.13705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:04.467515Z","time spent":"669.527449ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T14:18:05.136913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.105713ms","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-16T14:18:05.137138Z","caller":"traceutil/trace.go:171","msg":"trace[932291072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2071; }","duration":"350.369137ms","start":"2026-07-16T14:18:04.78676Z","end":"2026-07-16T14:18:05.137129Z","steps":["trace[932291072] 'agreement among raft nodes before linearized reading' (duration: 350.073822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.137173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:04.786733Z","time spent":"350.430768ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-16T14:18:05.137363Z","caller":"traceutil/trace.go:171","msg":"trace[1618059093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2071; }","duration":"656.304635ms","start":"2026-07-16T14:18:04.480435Z","end":"2026-07-16T14:18:05.13674Z","steps":["trace[1618059093] 'agreement among raft nodes before linearized reading' (duration: 656.225984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:05.137428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:04.48043Z","time spent":"656.964254ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T14:18:25.311161Z","caller":"traceutil/trace.go:171","msg":"trace[2054846106] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"113.502544ms","start":"2026-07-16T14:18:25.197601Z","end":"2026-07-16T14:18:25.311103Z","steps":["trace[2054846106] 'process raft request' (duration: 113.233971ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:25.84971Z","caller":"traceutil/trace.go:171","msg":"trace[1197487612] linearizableReadLoop","detail":"{readStateIndex:2268; appliedIndex:2266; }","duration":"599.514975ms","start":"2026-07-16T14:18:25.250173Z","end":"2026-07-16T14:18:25.849688Z","steps":["trace[1197487612] 'read index received' (duration: 60.740578ms)","trace[1197487612] 'applied index is now lower than readState.Index' (duration: 538.773557ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:18:25.849774Z","caller":"traceutil/trace.go:171","msg":"trace[2119188763] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"588.737629ms","start":"2026-07-16T14:18:25.261Z","end":"2026-07-16T14:18:25.849738Z","steps":["trace[2119188763] 'process raft request' (duration: 588.615137ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:25.849817Z","caller":"traceutil/trace.go:171","msg":"trace[225946271] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"577.525176ms","start":"2026-07-16T14:18:25.272281Z","end":"2026-07-16T14:18:25.849806Z","steps":["trace[225946271] 'process raft request' (duration: 577.374955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:25.849931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.272265Z","time spent":"577.582617ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:25.849999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.260986Z","time spent":"588.8584ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:25.850238Z","caller":"traceutil/trace.go:171","msg":"trace[512053417] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"649.471586ms","start":"2026-07-16T14:18:25.200743Z","end":"2026-07-16T14:18:25.850214Z","steps":["trace[512053417] 'process raft request' (duration: 648.725516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:25.850856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.200715Z","time spent":"650.097063ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:25.850236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.067352ms","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-16T14:18:25.850986Z","caller":"traceutil/trace.go:171","msg":"trace[131689373] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2192; }","duration":"600.842301ms","start":"2026-07-16T14:18:25.25013Z","end":"2026-07-16T14:18:25.850972Z","steps":["trace[131689373] 'agreement among raft nodes before linearized reading' (duration: 599.92031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:25.851034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.250117Z","time spent":"600.908712ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-16T14:18:25.864352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.525281ms","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-16T14:18:25.864381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.420775ms","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-16T14:18:25.864426Z","caller":"traceutil/trace.go:171","msg":"trace[2057529603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2193; }","duration":"609.638212ms","start":"2026-07-16T14:18:25.254771Z","end":"2026-07-16T14:18:25.86441Z","steps":["trace[2057529603] 'agreement among raft nodes before linearized reading' (duration: 609.430129ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:25.864432Z","caller":"traceutil/trace.go:171","msg":"trace[1132655607] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2193; }","duration":"596.508326ms","start":"2026-07-16T14:18:25.267911Z","end":"2026-07-16T14:18:25.864419Z","steps":["trace[1132655607] 'agreement among raft nodes before linearized reading' (duration: 596.344144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:25.864432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.344758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T14:18:25.864475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.254757Z","time spent":"609.709784ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T14:18:25.86448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.267899Z","time spent":"596.575728ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-16T14:18:25.864507Z","caller":"traceutil/trace.go:171","msg":"trace[751650666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2193; }","duration":"605.413269ms","start":"2026-07-16T14:18:25.259061Z","end":"2026-07-16T14:18:25.864474Z","steps":["trace[751650666] 'agreement among raft nodes before linearized reading' (duration: 605.335258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:25.86455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.25905Z","time spent":"605.47936ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T14:18:25.864541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.66893ms","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-16T14:18:25.864599Z","caller":"traceutil/trace.go:171","msg":"trace[1204015495] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"320.714532ms","start":"2026-07-16T14:18:25.543875Z","end":"2026-07-16T14:18:25.864589Z","steps":["trace[1204015495] 'process raft request' (duration: 320.180636ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:25.864624Z","caller":"traceutil/trace.go:171","msg":"trace[1567695034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2193; }","duration":"608.763431ms","start":"2026-07-16T14:18:25.255843Z","end":"2026-07-16T14:18:25.864606Z","steps":["trace[1567695034] 'agreement among raft nodes before linearized reading' (duration: 608.435927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:25.864673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.543847Z","time spent":"320.775133ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:25.864673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.25581Z","time spent":"608.854092ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T14:18:25.864369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.768871ms","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-16T14:18:25.864743Z","caller":"traceutil/trace.go:171","msg":"trace[149089315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2193; }","duration":"609.167196ms","start":"2026-07-16T14:18:25.255568Z","end":"2026-07-16T14:18:25.864735Z","steps":["trace[149089315] 'agreement among raft nodes before linearized reading' (duration: 608.732411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:25.864764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.255559Z","time spent":"609.198726ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T14:18:26.214759Z","caller":"traceutil/trace.go:171","msg":"trace[721645067] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"346.423706ms","start":"2026-07-16T14:18:25.868309Z","end":"2026-07-16T14:18:26.214733Z","steps":["trace[721645067] 'process raft request' (duration: 331.344196ms)","trace[721645067] 'compare' (duration: 14.719015ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:26.214919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.868294Z","time spent":"346.556817ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:26.215327Z","caller":"traceutil/trace.go:171","msg":"trace[1066447920] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"346.972043ms","start":"2026-07-16T14:18:25.868324Z","end":"2026-07-16T14:18:26.215296Z","steps":["trace[1066447920] 'process raft request' (duration: 346.824401ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:26.215406Z","caller":"traceutil/trace.go:171","msg":"trace[402197840] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"347.076834ms","start":"2026-07-16T14:18:25.868321Z","end":"2026-07-16T14:18:26.215398Z","steps":["trace[402197840] 'process raft request' (duration: 346.358155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:26.215466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.8683Z","time spent":"347.132135ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:26.215485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.8683Z","time spent":"347.105864ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:26.215356Z","caller":"traceutil/trace.go:171","msg":"trace[1959031784] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"346.976423ms","start":"2026-07-16T14:18:25.868367Z","end":"2026-07-16T14:18:26.215343Z","steps":["trace[1959031784] 'process raft request' (duration: 346.888701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:26.216591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:25.868353Z","time spent":"347.54343ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:26.848143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.306368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:18:26.848212Z","caller":"traceutil/trace.go:171","msg":"trace[1463279009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2198; }","duration":"199.410749ms","start":"2026-07-16T14:18:26.648784Z","end":"2026-07-16T14:18:26.848195Z","steps":["trace[1463279009] 'range keys from in-memory index tree' (duration: 199.227467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:26.84833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.597733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:18:26.848363Z","caller":"traceutil/trace.go:171","msg":"trace[1341346432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2198; }","duration":"462.665323ms","start":"2026-07-16T14:18:26.38569Z","end":"2026-07-16T14:18:26.848356Z","steps":["trace[1341346432] 'range keys from in-memory index tree' (duration: 462.521522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:26.848378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:26.38567Z","time spent":"462.703523ms","remote":"127.0.0.1:54792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:18:27.798073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.393962ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-16T14:18:27.798388Z","caller":"traceutil/trace.go:171","msg":"trace[593353326] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2276; }","duration":"666.738187ms","start":"2026-07-16T14:18:27.131627Z","end":"2026-07-16T14:18:27.798366Z","steps":["trace[593353326] 'read index received' (duration: 19.95089ms)","trace[593353326] 'applied index is now lower than readState.Index' (duration: 646.771756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:27.798562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.925849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T14:18:27.798779Z","caller":"traceutil/trace.go:171","msg":"trace[390391352] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"481.400752ms","start":"2026-07-16T14:18:27.317345Z","end":"2026-07-16T14:18:27.798746Z","steps":["trace[390391352] 'process raft request' (duration: 480.834725ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:27.798835Z","caller":"traceutil/trace.go:171","msg":"trace[1187489166] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2198; }","duration":"667.242082ms","start":"2026-07-16T14:18:27.131574Z","end":"2026-07-16T14:18:27.798816Z","steps":["trace[1187489166] 'agreement among raft nodes before linearized reading' (duration: 666.923068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:27.798878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:27.131548Z","time spent":"667.317324ms","remote":"127.0.0.1:55112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-16T14:18:27.798941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:27.317315Z","time spent":"481.535685ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:27.847294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.852513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:18:27.847358Z","caller":"traceutil/trace.go:171","msg":"trace[1849654379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2199; }","duration":"198.958944ms","start":"2026-07-16T14:18:27.648382Z","end":"2026-07-16T14:18:27.847341Z","steps":["trace[1849654379] 'agreement among raft nodes before linearized reading' (duration: 150.344404ms)","trace[1849654379] 'range keys from in-memory index tree' (duration: 48.508378ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:18:28.144169Z","caller":"traceutil/trace.go:171","msg":"trace[2140406711] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"287.80384ms","start":"2026-07-16T14:18:27.85634Z","end":"2026-07-16T14:18:28.144144Z","steps":["trace[2140406711] 'process raft request' (duration: 287.708549ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:28.14434Z","caller":"traceutil/trace.go:171","msg":"trace[805920643] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"287.938702ms","start":"2026-07-16T14:18:27.856386Z","end":"2026-07-16T14:18:28.144325Z","steps":["trace[805920643] 'process raft request' (duration: 287.720019ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:28.144189Z","caller":"traceutil/trace.go:171","msg":"trace[1995774758] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"287.83739ms","start":"2026-07-16T14:18:27.856322Z","end":"2026-07-16T14:18:28.14416Z","steps":["trace[1995774758] 'process raft request' (duration: 287.565257ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:28.144679Z","caller":"traceutil/trace.go:171","msg":"trace[566069090] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2278; }","duration":"281.290538ms","start":"2026-07-16T14:18:27.862995Z","end":"2026-07-16T14:18:28.144285Z","steps":["trace[566069090] 'read index received' (duration: 26.880417ms)","trace[566069090] 'applied index is now lower than readState.Index' (duration: 254.408861ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:28.144731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.714383ms","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-16T14:18:28.144865Z","caller":"traceutil/trace.go:171","msg":"trace[1578137910] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2203; }","duration":"281.889786ms","start":"2026-07-16T14:18:27.862953Z","end":"2026-07-16T14:18:28.144843Z","steps":["trace[1578137910] 'agreement among raft nodes before linearized reading' (duration: 281.636463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:28.144886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.360108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T14:18:28.144927Z","caller":"traceutil/trace.go:171","msg":"trace[1988910912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2203; }","duration":"269.4502ms","start":"2026-07-16T14:18:27.875466Z","end":"2026-07-16T14:18:28.144916Z","steps":["trace[1988910912] 'agreement among raft nodes before linearized reading' (duration: 269.298388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:29.290207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066889115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-16T14:18:29.290878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067578913s","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-16T14:18:29.290975Z","caller":"traceutil/trace.go:171","msg":"trace[238645240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2204; }","duration":"1.067650534s","start":"2026-07-16T14:18:28.223277Z","end":"2026-07-16T14:18:29.290928Z","steps":["trace[238645240] 'range keys from in-memory index tree' (duration: 1.066754203s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:29.291057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:28.223275Z","time spent":"1.067763216s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T14:18:29.290308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.397516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:18:29.291848Z","caller":"traceutil/trace.go:171","msg":"trace[1832970270] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2283; }","duration":"641.461553ms","start":"2026-07-16T14:18:28.650356Z","end":"2026-07-16T14:18:29.291817Z","steps":["trace[1832970270] 'read index received' (duration: 154.367246ms)","trace[1832970270] 'applied index is now lower than readState.Index' (duration: 487.091007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:29.29031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067008396s","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-16T14:18:29.292112Z","caller":"traceutil/trace.go:171","msg":"trace[246687095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2204; }","duration":"1.06886461s","start":"2026-07-16T14:18:28.223228Z","end":"2026-07-16T14:18:29.292093Z","steps":["trace[246687095] 'range keys from in-memory index tree' (duration: 1.066792274s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:29.292238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:28.223216Z","time spent":"1.068993201s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T14:18:29.290021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066804593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T14:18:29.29232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.637422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-16T14:18:29.292271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.816988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:18:29.292403Z","caller":"traceutil/trace.go:171","msg":"trace[1356808865] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2205; }","duration":"523.766184ms","start":"2026-07-16T14:18:28.768612Z","end":"2026-07-16T14:18:29.292378Z","steps":["trace[1356808865] 'agreement among raft nodes before linearized reading' (duration: 523.589472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:29.2925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:28.76859Z","time spent":"523.897686ms","remote":"127.0.0.1:55140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-16T14:18:29.292403Z","caller":"traceutil/trace.go:171","msg":"trace[339414017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2205; }","duration":"642.077072ms","start":"2026-07-16T14:18:28.650311Z","end":"2026-07-16T14:18:29.292388Z","steps":["trace[339414017] 'agreement among raft nodes before linearized reading' (duration: 641.800158ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:29.292386Z","caller":"traceutil/trace.go:171","msg":"trace[533948537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2204; }","duration":"1.069209733s","start":"2026-07-16T14:18:28.223155Z","end":"2026-07-16T14:18:29.292365Z","steps":["trace[533948537] 'range keys from in-memory index tree' (duration: 1.066638152s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:29.292709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:28.650294Z","time spent":"642.362225ms","remote":"127.0.0.1:54792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:18:29.292812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:28.223139Z","time spent":"1.069522738s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T14:18:29.290963Z","caller":"traceutil/trace.go:171","msg":"trace[13367361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2204; }","duration":"1.067686725s","start":"2026-07-16T14:18:28.22326Z","end":"2026-07-16T14:18:29.290946Z","steps":["trace[13367361] 'range keys from in-memory index tree' (duration: 1.066865645s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:29.293137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:28.223255Z","time spent":"1.069862102s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-16T14:18:29.293317Z","caller":"traceutil/trace.go:171","msg":"trace[613092003] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"931.23339ms","start":"2026-07-16T14:18:28.360968Z","end":"2026-07-16T14:18:29.292202Z","steps":["trace[613092003] 'process raft request' (duration: 443.87405ms)","trace[613092003] 'compare' (duration: 484.999941ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:29.293464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:28.360944Z","time spent":"932.446095ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:29.293652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.625711ms","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-16T14:18:29.293707Z","caller":"traceutil/trace.go:171","msg":"trace[1061866800] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2205; }","duration":"141.712902ms","start":"2026-07-16T14:18:29.151979Z","end":"2026-07-16T14:18:29.293691Z","steps":["trace[1061866800] 'agreement among raft nodes before linearized reading' (duration: 140.280694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:29.803877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.462395ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:18:29.803973Z","caller":"traceutil/trace.go:171","msg":"trace[2101384214] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2205; }","duration":"357.567946ms","start":"2026-07-16T14:18:29.446392Z","end":"2026-07-16T14:18:29.80396Z","steps":["trace[2101384214] 'range keys from in-memory index tree' (duration: 357.449695ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:29.804343Z","caller":"traceutil/trace.go:171","msg":"trace[196896624] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"507.761315ms","start":"2026-07-16T14:18:29.296562Z","end":"2026-07-16T14:18:29.804324Z","steps":["trace[196896624] 'process raft request' (duration: 490.44673ms)","trace[196896624] 'compare' (duration: 17.072372ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:29.804669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:29.296542Z","time spent":"508.065128ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:30.386666Z","caller":"traceutil/trace.go:171","msg":"trace[247446350] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"1.089506321s","start":"2026-07-16T14:18:29.297141Z","end":"2026-07-16T14:18:30.386648Z","steps":["trace[247446350] 'process raft request' (duration: 1.05789799s)","trace[247446350] 'compare' (duration: 31.50275ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:30.386775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:29.297123Z","time spent":"1.089588592s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:30.386923Z","caller":"traceutil/trace.go:171","msg":"trace[300081845] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"1.088272386s","start":"2026-07-16T14:18:29.29863Z","end":"2026-07-16T14:18:30.386902Z","steps":["trace[300081845] 'process raft request' (duration: 1.087996013s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:30.38706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:29.298615Z","time spent":"1.088373087s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:30.387448Z","caller":"traceutil/trace.go:171","msg":"trace[267529540] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"1.087224923s","start":"2026-07-16T14:18:29.300211Z","end":"2026-07-16T14:18:30.387435Z","steps":["trace[267529540] 'process raft request' (duration: 1.086616175s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:30.387564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:29.300198Z","time spent":"1.087284053s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:30.387706Z","caller":"traceutil/trace.go:171","msg":"trace[79553317] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2284; }","duration":"673.860552ms","start":"2026-07-16T14:18:29.713828Z","end":"2026-07-16T14:18:30.387689Z","steps":["trace[79553317] 'read index received' (duration: 73.175371ms)","trace[79553317] 'applied index is now lower than readState.Index' (duration: 600.683841ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:18:30.387754Z","caller":"traceutil/trace.go:171","msg":"trace[1888866654] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"762.429934ms","start":"2026-07-16T14:18:29.625308Z","end":"2026-07-16T14:18:30.387738Z","steps":["trace[1888866654] 'process raft request' (duration: 762.085849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:30.388571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:29.625268Z","time spent":"763.229463ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:30.387904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.079244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:18:30.388862Z","caller":"traceutil/trace.go:171","msg":"trace[1859995128] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2210; }","duration":"675.019547ms","start":"2026-07-16T14:18:29.713774Z","end":"2026-07-16T14:18:30.388794Z","steps":["trace[1859995128] 'agreement among raft nodes before linearized reading' (duration: 674.093815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:30.388996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:29.713754Z","time spent":"675.176368ms","remote":"127.0.0.1:48364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-07-16T14:18:30.889095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971871369206247,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T14:18:31.187449Z","caller":"traceutil/trace.go:171","msg":"trace[814237473] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2289; }","duration":"798.992129ms","start":"2026-07-16T14:18:30.388433Z","end":"2026-07-16T14:18:31.187425Z","steps":["trace[814237473] 'read index received' (duration: 793.037085ms)","trace[814237473] 'applied index is now lower than readState.Index' (duration: 5.954064ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:31.187924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.70449ms","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-16T14:18:31.188005Z","caller":"traceutil/trace.go:171","msg":"trace[1031972446] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2214; }","duration":"884.822501ms","start":"2026-07-16T14:18:30.303163Z","end":"2026-07-16T14:18:31.187986Z","steps":["trace[1031972446] 'agreement among raft nodes before linearized reading' (duration: 884.586039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:31.188048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:30.303141Z","time spent":"884.896602ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-16T14:18:31.188103Z","caller":"traceutil/trace.go:171","msg":"trace[1242152837] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"1.380381129s","start":"2026-07-16T14:18:29.807184Z","end":"2026-07-16T14:18:31.187565Z","steps":["trace[1242152837] 'process raft request' (duration: 1.374397826s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:31.188345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:29.807164Z","time spent":"1.381015248s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:31.188382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.841493ms","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-16T14:18:31.188426Z","caller":"traceutil/trace.go:171","msg":"trace[748105744] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"1.038336133s","start":"2026-07-16T14:18:30.15007Z","end":"2026-07-16T14:18:31.188407Z","steps":["trace[748105744] 'process raft request' (duration: 1.03731606s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:31.187984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.68088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T14:18:31.188483Z","caller":"traceutil/trace.go:171","msg":"trace[1456061963] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"1.038549785s","start":"2026-07-16T14:18:30.14986Z","end":"2026-07-16T14:18:31.18841Z","steps":["trace[1456061963] 'process raft request' (duration: 1.037486122s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:31.189087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:30.149853Z","time spent":"1.039188503s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:31.188856Z","caller":"traceutil/trace.go:171","msg":"trace[1893641493] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2214; }","duration":"671.54917ms","start":"2026-07-16T14:18:30.517227Z","end":"2026-07-16T14:18:31.188776Z","steps":["trace[1893641493] 'agreement among raft nodes before linearized reading' (duration: 670.663459ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:31.188507Z","caller":"traceutil/trace.go:171","msg":"trace[437384555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2214; }","duration":"998.947255ms","start":"2026-07-16T14:18:30.189485Z","end":"2026-07-16T14:18:31.188432Z","steps":["trace[437384555] 'agreement among raft nodes before linearized reading' (duration: 998.736442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:31.189239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:30.189471Z","time spent":"999.729514ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T14:18:31.188531Z","caller":"traceutil/trace.go:171","msg":"trace[629181825] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"1.038878299s","start":"2026-07-16T14:18:30.149629Z","end":"2026-07-16T14:18:31.188508Z","steps":["trace[629181825] 'process raft request' (duration: 1.037635234s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:31.189368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:30.149609Z","time spent":"1.039719389s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:31.188544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.687072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:18:31.189458Z","caller":"traceutil/trace.go:171","msg":"trace[922248348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2214; }","duration":"538.636903ms","start":"2026-07-16T14:18:30.650806Z","end":"2026-07-16T14:18:31.189443Z","steps":["trace[922248348] 'agreement among raft nodes before linearized reading' (duration: 537.695342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:31.189512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:30.650786Z","time spent":"538.711904ms","remote":"127.0.0.1:54792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:18:31.188594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:30.150055Z","time spent":"1.038474434s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:18:31.189357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:30.517203Z","time spent":"672.129497ms","remote":"127.0.0.1:54906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":20,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-16T14:20:01.642536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.462668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-haproxy-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:20:01.642566Z","caller":"traceutil/trace.go:171","msg":"trace[1783037744] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"146.688379ms","start":"2026-07-16T14:20:01.495851Z","end":"2026-07-16T14:20:01.642539Z","steps":["trace[1783037744] 'process raft request' (duration: 146.147384ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:20:01.64264Z","caller":"traceutil/trace.go:171","msg":"trace[313031689] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-haproxy-metrics; range_end:; response_count:0; response_revision:2696; }","duration":"166.62938ms","start":"2026-07-16T14:20:01.47599Z","end":"2026-07-16T14:20:01.642619Z","steps":["trace[313031689] 'range keys from in-memory index tree' (duration: 166.356457ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:20:01.780298Z","caller":"traceutil/trace.go:171","msg":"trace[336805953] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"280.497378ms","start":"2026-07-16T14:20:01.499763Z","end":"2026-07-16T14:20:01.78026Z","steps":["trace[336805953] 'process raft request' (duration: 260.211404ms)","trace[336805953] 'compare' (duration: 20.002841ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:20:01.780389Z","caller":"traceutil/trace.go:171","msg":"trace[1721780514] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"278.350127ms","start":"2026-07-16T14:20:01.502015Z","end":"2026-07-16T14:20:01.780365Z","steps":["trace[1721780514] 'process raft request' (duration: 278.243346ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:20:01.780339Z","caller":"traceutil/trace.go:171","msg":"trace[219218670] linearizableReadLoop","detail":"{readStateIndex:2797; appliedIndex:2793; }","duration":"221.396013ms","start":"2026-07-16T14:20:01.558917Z","end":"2026-07-16T14:20:01.780313Z","steps":["trace[219218670] 'read index received' (duration: 83.094118ms)","trace[219218670] 'applied index is now lower than readState.Index' (duration: 138.300645ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:20:01.780493Z","caller":"traceutil/trace.go:171","msg":"trace[1073580982] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"280.116134ms","start":"2026-07-16T14:20:01.500262Z","end":"2026-07-16T14:20:01.780378Z","steps":["trace[1073580982] 'process raft request' (duration: 279.860252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:20:01.780676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.752666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-16T14:20:01.780945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.8638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:20:01.780989Z","caller":"traceutil/trace.go:171","msg":"trace[994741232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2700; }","duration":"130.96018ms","start":"2026-07-16T14:20:01.650018Z","end":"2026-07-16T14:20:01.780978Z","steps":["trace[994741232] 'agreement among raft nodes before linearized reading' (duration: 130.858499ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:20:01.780992Z","caller":"traceutil/trace.go:171","msg":"trace[1456978342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2700; }","duration":"222.18121ms","start":"2026-07-16T14:20:01.558783Z","end":"2026-07-16T14:20:01.780964Z","steps":["trace[1456978342] 'agreement among raft nodes before linearized reading' (duration: 221.789346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:20:01.78108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.276984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128978"} {"level":"info","ts":"2026-07-16T14:20:01.781167Z","caller":"traceutil/trace.go:171","msg":"trace[1422837879] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2700; }","duration":"132.444925ms","start":"2026-07-16T14:20:01.648705Z","end":"2026-07-16T14:20:01.78115Z","steps":["trace[1422837879] 'agreement among raft nodes before linearized reading' (duration: 131.94105ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:20:26.056667Z","caller":"traceutil/trace.go:171","msg":"trace[750669862] transaction","detail":"{read_only:false; response_revision:2920; number_of_response:1; }","duration":"198.884649ms","start":"2026-07-16T14:20:25.857758Z","end":"2026-07-16T14:20:26.056642Z","steps":["trace[750669862] 'process raft request' (duration: 198.732482ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:20:26.057351Z","caller":"traceutil/trace.go:171","msg":"trace[1920125619] linearizableReadLoop","detail":"{readStateIndex:3026; appliedIndex:3023; }","duration":"132.598559ms","start":"2026-07-16T14:20:25.924734Z","end":"2026-07-16T14:20:26.057333Z","steps":["trace[1920125619] 'read index received' (duration: 131.773662ms)","trace[1920125619] 'applied index is now lower than readState.Index' (duration: 824.077µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:20:26.057567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.819669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T14:20:26.057612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.231422ms","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-16T14:20:26.057622Z","caller":"traceutil/trace.go:171","msg":"trace[1278890570] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2922; }","duration":"132.944234ms","start":"2026-07-16T14:20:25.924664Z","end":"2026-07-16T14:20:26.057608Z","steps":["trace[1278890570] 'agreement among raft nodes before linearized reading' (duration: 132.838889ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:20:26.05764Z","caller":"traceutil/trace.go:171","msg":"trace[1573263903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2922; }","duration":"132.315626ms","start":"2026-07-16T14:20:25.925317Z","end":"2026-07-16T14:20:26.057633Z","steps":["trace[1573263903] 'agreement among raft nodes before linearized reading' (duration: 132.202641ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:20:26.057817Z","caller":"traceutil/trace.go:171","msg":"trace[16688482] transaction","detail":"{read_only:false; response_revision:2921; number_of_response:1; }","duration":"194.014653ms","start":"2026-07-16T14:20:25.863791Z","end":"2026-07-16T14:20:26.057805Z","steps":["trace[16688482] 'process raft request' (duration: 193.435938ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:20:26.057841Z","caller":"traceutil/trace.go:171","msg":"trace[1333264996] transaction","detail":"{read_only:false; response_revision:2922; number_of_response:1; }","duration":"192.903734ms","start":"2026-07-16T14:20:25.86493Z","end":"2026-07-16T14:20:26.057833Z","steps":["trace[1333264996] 'process raft request' (duration: 192.37675ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:21:03.273329Z","caller":"traceutil/trace.go:171","msg":"trace[851636175] linearizableReadLoop","detail":"{readStateIndex:3278; appliedIndex:3277; }","duration":"121.163537ms","start":"2026-07-16T14:21:03.152138Z","end":"2026-07-16T14:21:03.273301Z","steps":["trace[851636175] 'read index received' (duration: 120.903917ms)","trace[851636175] 'applied index is now lower than readState.Index' (duration: 258.59µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:21:03.273398Z","caller":"traceutil/trace.go:171","msg":"trace[175856834] transaction","detail":"{read_only:false; response_revision:3165; number_of_response:1; }","duration":"129.817732ms","start":"2026-07-16T14:21:03.14354Z","end":"2026-07-16T14:21:03.273358Z","steps":["trace[175856834] 'process raft request' (duration: 129.607304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:21:03.273611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.449687ms","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-16T14:21:03.273648Z","caller":"traceutil/trace.go:171","msg":"trace[328814159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3165; }","duration":"121.508429ms","start":"2026-07-16T14:21:03.152129Z","end":"2026-07-16T14:21:03.273638Z","steps":["trace[328814159] 'agreement among raft nodes before linearized reading' (duration: 121.318102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:21:03.274049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.768803ms","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-16T14:21:03.27447Z","caller":"traceutil/trace.go:171","msg":"trace[2017941713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3165; }","duration":"120.953359ms","start":"2026-07-16T14:21:03.153206Z","end":"2026-07-16T14:21:03.27416Z","steps":["trace[2017941713] 'agreement among raft nodes before linearized reading' (duration: 120.43692ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:21:03.49357Z","caller":"traceutil/trace.go:171","msg":"trace[19924455] transaction","detail":"{read_only:false; response_revision:3167; number_of_response:1; }","duration":"206.584724ms","start":"2026-07-16T14:21:03.286961Z","end":"2026-07-16T14:21:03.493545Z","steps":["trace[19924455] 'process raft request' (duration: 206.536762ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:21:03.493635Z","caller":"traceutil/trace.go:171","msg":"trace[631463663] transaction","detail":"{read_only:false; response_revision:3166; number_of_response:1; }","duration":"207.499936ms","start":"2026-07-16T14:21:03.286097Z","end":"2026-07-16T14:21:03.493597Z","steps":["trace[631463663] 'process raft request' (duration: 154.784069ms)","trace[631463663] 'compare' (duration: 52.462178ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:21:24.191643Z","caller":"traceutil/trace.go:171","msg":"trace[1328033621] transaction","detail":"{read_only:false; response_revision:3276; number_of_response:1; }","duration":"197.984389ms","start":"2026-07-16T14:21:23.99363Z","end":"2026-07-16T14:21:24.191614Z","steps":["trace[1328033621] 'process raft request' (duration: 197.809433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:21:29.586318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.044699ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:21:29.586611Z","caller":"traceutil/trace.go:171","msg":"trace[158913235] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3301; }","duration":"140.361879ms","start":"2026-07-16T14:21:29.446212Z","end":"2026-07-16T14:21:29.586574Z","steps":["trace[158913235] 'range keys from in-memory index tree' (duration: 140.012888ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:21:29.804245Z","caller":"traceutil/trace.go:171","msg":"trace[430532028] transaction","detail":"{read_only:false; response_revision:3302; number_of_response:1; }","duration":"193.32081ms","start":"2026-07-16T14:21:29.610881Z","end":"2026-07-16T14:21:29.804202Z","steps":["trace[430532028] 'process raft request' (duration: 193.096763ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:21:29.838936Z","caller":"traceutil/trace.go:171","msg":"trace[1101351734] transaction","detail":"{read_only:false; response_revision:3304; number_of_response:1; }","duration":"155.755764ms","start":"2026-07-16T14:21:29.683146Z","end":"2026-07-16T14:21:29.838902Z","steps":["trace[1101351734] 'process raft request' (duration: 155.683431ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:21:29.839018Z","caller":"traceutil/trace.go:171","msg":"trace[2060218413] transaction","detail":"{read_only:false; response_revision:3303; number_of_response:1; }","duration":"227.24708ms","start":"2026-07-16T14:21:29.611733Z","end":"2026-07-16T14:21:29.83898Z","steps":["trace[2060218413] 'process raft request' (duration: 226.892049ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:22:19.963962Z","caller":"traceutil/trace.go:171","msg":"trace[1765065641] transaction","detail":"{read_only:false; response_revision:3564; number_of_response:1; }","duration":"298.188093ms","start":"2026-07-16T14:22:19.665743Z","end":"2026-07-16T14:22:19.963931Z","steps":["trace[1765065641] 'process raft request' (duration: 298.015289ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:24:54.463848Z","caller":"traceutil/trace.go:171","msg":"trace[549853189] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4547; }","duration":"114.907157ms","start":"2026-07-16T14:24:54.348912Z","end":"2026-07-16T14:24:54.463819Z","steps":["trace[549853189] 'process raft request' (duration: 114.796945ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:24:55.383995Z","caller":"traceutil/trace.go:171","msg":"trace[1376427572] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"123.697512ms","start":"2026-07-16T14:24:55.260272Z","end":"2026-07-16T14:24:55.38397Z","steps":["trace[1376427572] 'process raft request' (duration: 82.333605ms)","trace[1376427572] 'compare' (duration: 41.208254ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:24:55.38425Z","caller":"traceutil/trace.go:171","msg":"trace[1684953696] linearizableReadLoop","detail":"{readStateIndex:4739; appliedIndex:4736; }","duration":"114.679149ms","start":"2026-07-16T14:24:55.26956Z","end":"2026-07-16T14:24:55.384239Z","steps":["trace[1684953696] 'read index received' (duration: 42.418865ms)","trace[1684953696] 'applied index is now lower than readState.Index' (duration: 72.259324ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:24:55.384335Z","caller":"traceutil/trace.go:171","msg":"trace[196169222] transaction","detail":"{read_only:false; response_revision:4559; number_of_response:1; }","duration":"121.911072ms","start":"2026-07-16T14:24:55.262415Z","end":"2026-07-16T14:24:55.384326Z","steps":["trace[196169222] 'process raft request' (duration: 121.516755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:24:55.384515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.945883ms","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-16T14:24:55.384556Z","caller":"traceutil/trace.go:171","msg":"trace[416058469] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4560; }","duration":"115.022755ms","start":"2026-07-16T14:24:55.269524Z","end":"2026-07-16T14:24:55.384547Z","steps":["trace[416058469] 'agreement among raft nodes before linearized reading' (duration: 114.876922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:24:55.384602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.709528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:24:55.384663Z","caller":"traceutil/trace.go:171","msg":"trace[1492587656] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:4560; }","duration":"104.80188ms","start":"2026-07-16T14:24:55.279845Z","end":"2026-07-16T14:24:55.384647Z","steps":["trace[1492587656] 'agreement among raft nodes before linearized reading' (duration: 104.711548ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:24:56.234351Z","caller":"traceutil/trace.go:171","msg":"trace[1464861115] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"103.945063ms","start":"2026-07-16T14:24:56.130379Z","end":"2026-07-16T14:24:56.234324Z","steps":["trace[1464861115] 'process raft request' (duration: 54.082633ms)","trace[1464861115] 'compare' (duration: 49.636726ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:24:56.236371Z","caller":"traceutil/trace.go:171","msg":"trace[941746193] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"104.92781ms","start":"2026-07-16T14:24:56.131419Z","end":"2026-07-16T14:24:56.236347Z","steps":["trace[941746193] 'process raft request' (duration: 104.738486ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:24:56.236486Z","caller":"traceutil/trace.go:171","msg":"trace[599961] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"104.851269ms","start":"2026-07-16T14:24:56.131612Z","end":"2026-07-16T14:24:56.236463Z","steps":["trace[599961] 'process raft request' (duration: 104.680846ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:24:59.324503Z","caller":"traceutil/trace.go:171","msg":"trace[1742231400] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"229.478195ms","start":"2026-07-16T14:24:59.095005Z","end":"2026-07-16T14:24:59.324483Z","steps":["trace[1742231400] 'process raft request' (duration: 229.250141ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:24:59.326494Z","caller":"traceutil/trace.go:171","msg":"trace[31363000] transaction","detail":"{read_only:false; response_revision:4595; number_of_response:1; }","duration":"101.821799ms","start":"2026-07-16T14:24:59.224656Z","end":"2026-07-16T14:24:59.326478Z","steps":["trace[31363000] 'process raft request' (duration: 101.699507ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:24:59.326771Z","caller":"traceutil/trace.go:171","msg":"trace[1543574286] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"100.773471ms","start":"2026-07-16T14:24:59.225958Z","end":"2026-07-16T14:24:59.326732Z","steps":["trace[1543574286] 'process raft request' (duration: 100.486156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:07.706695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.989753ms","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-16T14:25:07.70677Z","caller":"traceutil/trace.go:171","msg":"trace[1112197079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4982; }","duration":"116.112605ms","start":"2026-07-16T14:25:07.590643Z","end":"2026-07-16T14:25:07.706755Z","steps":["trace[1112197079] 'range keys from in-memory index tree' (duration: 115.734219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:07.707318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.194998ms","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-16T14:25:07.70735Z","caller":"traceutil/trace.go:171","msg":"trace[1804485615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4982; }","duration":"208.267219ms","start":"2026-07-16T14:25:07.499075Z","end":"2026-07-16T14:25:07.707343Z","steps":["trace[1804485615] 'range keys from in-memory index tree' (duration: 207.76347ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:08.815341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2779} {"level":"info","ts":"2026-07-16T14:25:08.883099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2779,"took":"66.522653ms","hash":4233335054,"current-db-size-bytes":50470912,"current-db-size":"50 MB","current-db-size-in-use-bytes":25985024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-16T14:25:08.883156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4233335054,"revision":2779,"compact-revision":-1} {"level":"warn","ts":"2026-07-16T14:25:12.880734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.236086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40490a89-7632-40f1-b13c-21a1bc0f3e8d\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-07-16T14:25:12.880832Z","caller":"traceutil/trace.go:171","msg":"trace[2113343229] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40490a89-7632-40f1-b13c-21a1bc0f3e8d; range_end:; response_count:1; response_revision:5102; }","duration":"470.376038ms","start":"2026-07-16T14:25:12.410437Z","end":"2026-07-16T14:25:12.880813Z","steps":["trace[2113343229] 'range keys from in-memory index tree' (duration: 469.978492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:12.880878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:12.410425Z","time spent":"470.44345ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3796,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40490a89-7632-40f1-b13c-21a1bc0f3e8d\" "} {"level":"warn","ts":"2026-07-16T14:25:12.881386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.546274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd6de77f-b56c-4f50-911e-822a282c0969\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-07-16T14:25:12.881499Z","caller":"traceutil/trace.go:171","msg":"trace[1317722846] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bd6de77f-b56c-4f50-911e-822a282c0969; range_end:; response_count:1; response_revision:5102; }","duration":"102.706037ms","start":"2026-07-16T14:25:12.778764Z","end":"2026-07-16T14:25:12.88147Z","steps":["trace[1317722846] 'range keys from in-memory index tree' (duration: 102.348211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:12.881895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.31877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-07-16T14:25:12.882108Z","caller":"traceutil/trace.go:171","msg":"trace[1967900598] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5102; }","duration":"499.562043ms","start":"2026-07-16T14:25:12.382525Z","end":"2026-07-16T14:25:12.882087Z","steps":["trace[1967900598] 'range keys from in-memory index tree' (duration: 498.547856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:12.882164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:12.38251Z","time spent":"499.634615ms","remote":"127.0.0.1:54914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2206995,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-07-16T14:25:12.882474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.639894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:25:12.882625Z","caller":"traceutil/trace.go:171","msg":"trace[1183867671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5102; }","duration":"233.824817ms","start":"2026-07-16T14:25:12.648784Z","end":"2026-07-16T14:25:12.882608Z","steps":["trace[1183867671] 'range keys from in-memory index tree' (duration: 231.867424ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:14.945922Z","caller":"traceutil/trace.go:171","msg":"trace[1657304945] linearizableReadLoop","detail":"{readStateIndex:5309; appliedIndex:5308; }","duration":"297.541701ms","start":"2026-07-16T14:25:14.648364Z","end":"2026-07-16T14:25:14.945905Z","steps":["trace[1657304945] 'read index received' (duration: 297.378658ms)","trace[1657304945] 'applied index is now lower than readState.Index' (duration: 162.423µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:25:14.946021Z","caller":"traceutil/trace.go:171","msg":"trace[551130357] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"385.607441ms","start":"2026-07-16T14:25:14.560392Z","end":"2026-07-16T14:25:14.946Z","steps":["trace[551130357] 'process raft request' (duration: 385.397547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:14.946135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.766745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:25:14.946159Z","caller":"traceutil/trace.go:171","msg":"trace[705808285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5118; }","duration":"297.826335ms","start":"2026-07-16T14:25:14.648326Z","end":"2026-07-16T14:25:14.946152Z","steps":["trace[705808285] 'agreement among raft nodes before linearized reading' (duration: 297.666612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:14.946158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.515268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T14:25:14.946153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:14.560379Z","time spent":"385.699612ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4936,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:25:14.946189Z","caller":"traceutil/trace.go:171","msg":"trace[200526187] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5118; }","duration":"289.579479ms","start":"2026-07-16T14:25:14.656602Z","end":"2026-07-16T14:25:14.946181Z","steps":["trace[200526187] 'agreement among raft nodes before linearized reading' (duration: 289.414506ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:15.636006Z","caller":"traceutil/trace.go:171","msg":"trace[1043523418] linearizableReadLoop","detail":"{readStateIndex:5317; appliedIndex:5315; }","duration":"204.047556ms","start":"2026-07-16T14:25:15.431939Z","end":"2026-07-16T14:25:15.635987Z","steps":["trace[1043523418] 'read index received' (duration: 15.022039ms)","trace[1043523418] 'applied index is now lower than readState.Index' (duration: 189.024737ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:25:15.636083Z","caller":"traceutil/trace.go:171","msg":"trace[574944195] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"206.108911ms","start":"2026-07-16T14:25:15.429967Z","end":"2026-07-16T14:25:15.636076Z","steps":["trace[574944195] 'process raft request' (duration: 196.068865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:15.636201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.242199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T14:25:15.636254Z","caller":"traceutil/trace.go:171","msg":"trace[1163014085] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5126; }","duration":"204.347371ms","start":"2026-07-16T14:25:15.431893Z","end":"2026-07-16T14:25:15.636241Z","steps":["trace[1163014085] 'agreement among raft nodes before linearized reading' (duration: 204.25177ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:15.67282Z","caller":"traceutil/trace.go:171","msg":"trace[708437529] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"213.099587ms","start":"2026-07-16T14:25:15.459686Z","end":"2026-07-16T14:25:15.672785Z","steps":["trace[708437529] 'process raft request' (duration: 212.980175ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:15.673075Z","caller":"traceutil/trace.go:171","msg":"trace[212182673] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"128.977954ms","start":"2026-07-16T14:25:15.54407Z","end":"2026-07-16T14:25:15.673048Z","steps":["trace[212182673] 'process raft request' (duration: 128.647938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:15.673471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.52357ms","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":"warn","ts":"2026-07-16T14:25:15.673479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.408748ms","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-16T14:25:15.673526Z","caller":"traceutil/trace.go:171","msg":"trace[1546410513] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5129; }","duration":"156.881766ms","start":"2026-07-16T14:25:15.516628Z","end":"2026-07-16T14:25:15.673509Z","steps":["trace[1546410513] 'agreement among raft nodes before linearized reading' (duration: 156.420549ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:15.673522Z","caller":"traceutil/trace.go:171","msg":"trace[1270863359] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"219.283868ms","start":"2026-07-16T14:25:15.454217Z","end":"2026-07-16T14:25:15.673501Z","steps":["trace[1270863359] 'process raft request' (duration: 218.311882ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:15.673566Z","caller":"traceutil/trace.go:171","msg":"trace[270299682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5129; }","duration":"211.637362ms","start":"2026-07-16T14:25:15.461907Z","end":"2026-07-16T14:25:15.673544Z","steps":["trace[270299682] 'agreement among raft nodes before linearized reading' (duration: 211.186245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:20.741222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.985323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91ca8004-304a-412e-9d11-d9fd63aa8bf0\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-16T14:25:20.741323Z","caller":"traceutil/trace.go:171","msg":"trace[297485412] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91ca8004-304a-412e-9d11-d9fd63aa8bf0; range_end:; response_count:1; response_revision:5249; }","duration":"382.139855ms","start":"2026-07-16T14:25:20.35916Z","end":"2026-07-16T14:25:20.7413Z","steps":["trace[297485412] 'range keys from in-memory index tree' (duration: 381.865101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:20.741401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:20.359129Z","time spent":"382.260247ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91ca8004-304a-412e-9d11-d9fd63aa8bf0\" "} {"level":"info","ts":"2026-07-16T14:25:20.741921Z","caller":"traceutil/trace.go:171","msg":"trace[1449470881] linearizableReadLoop","detail":"{readStateIndex:5445; appliedIndex:5444; }","duration":"120.613011ms","start":"2026-07-16T14:25:20.621278Z","end":"2026-07-16T14:25:20.741891Z","steps":["trace[1449470881] 'read index received' (duration: 26.172659ms)","trace[1449470881] 'applied index is now lower than readState.Index' (duration: 94.438852ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:25:20.742499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.18918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:25:20.742615Z","caller":"traceutil/trace.go:171","msg":"trace[1777072515] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:5250; }","duration":"121.362903ms","start":"2026-07-16T14:25:20.621225Z","end":"2026-07-16T14:25:20.742588Z","steps":["trace[1777072515] 'agreement among raft nodes before linearized reading' (duration: 121.14199ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:20.742614Z","caller":"traceutil/trace.go:171","msg":"trace[1662059756] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"186.774778ms","start":"2026-07-16T14:25:20.555476Z","end":"2026-07-16T14:25:20.742251Z","steps":["trace[1662059756] 'process raft request' (duration: 91.978521ms)","trace[1662059756] 'compare' (duration: 94.032774ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:25:20.963898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.833069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-07-16T14:25:20.963991Z","caller":"traceutil/trace.go:171","msg":"trace[229501810] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5250; }","duration":"113.988872ms","start":"2026-07-16T14:25:20.849983Z","end":"2026-07-16T14:25:20.963971Z","steps":["trace[229501810] 'range keys from in-memory index tree' (duration: 113.596746ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:21.583779Z","caller":"traceutil/trace.go:171","msg":"trace[324150248] linearizableReadLoop","detail":"{readStateIndex:5448; appliedIndex:5447; }","duration":"138.246178ms","start":"2026-07-16T14:25:21.445507Z","end":"2026-07-16T14:25:21.583753Z","steps":["trace[324150248] 'read index received' (duration: 74.202µs)","trace[324150248] 'applied index is now lower than readState.Index' (duration: 138.170856ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:25:21.583812Z","caller":"traceutil/trace.go:171","msg":"trace[1351856846] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"138.316358ms","start":"2026-07-16T14:25:21.44548Z","end":"2026-07-16T14:25:21.583796Z","steps":["trace[1351856846] 'process raft request' (duration: 138.103545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:21.583908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.40166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-07-16T14:25:21.584039Z","caller":"traceutil/trace.go:171","msg":"trace[1821780794] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5253; }","duration":"138.600463ms","start":"2026-07-16T14:25:21.445427Z","end":"2026-07-16T14:25:21.584027Z","steps":["trace[1821780794] 'agreement among raft nodes before linearized reading' (duration: 138.41329ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:21.596207Z","caller":"traceutil/trace.go:171","msg":"trace[54004924] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"123.497775ms","start":"2026-07-16T14:25:21.472675Z","end":"2026-07-16T14:25:21.596173Z","steps":["trace[54004924] 'process raft request' (duration: 123.418354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:21.747499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.47323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:25:21.747637Z","caller":"traceutil/trace.go:171","msg":"trace[1632616324] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"124.518622ms","start":"2026-07-16T14:25:21.623098Z","end":"2026-07-16T14:25:21.747617Z","steps":["trace[1632616324] 'compare' (duration: 116.270027ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:21.950746Z","caller":"traceutil/trace.go:171","msg":"trace[1018061094] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"282.848289ms","start":"2026-07-16T14:25:21.667872Z","end":"2026-07-16T14:25:21.95072Z","steps":["trace[1018061094] 'process raft request' (duration: 282.714607ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:21.952172Z","caller":"traceutil/trace.go:171","msg":"trace[1163860430] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"253.708591ms","start":"2026-07-16T14:25:21.698453Z","end":"2026-07-16T14:25:21.952161Z","steps":["trace[1163860430] 'process raft request' (duration: 253.537349ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:21.95218Z","caller":"traceutil/trace.go:171","msg":"trace[2140791648] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"252.760185ms","start":"2026-07-16T14:25:21.699386Z","end":"2026-07-16T14:25:21.952146Z","steps":["trace[2140791648] 'process raft request' (duration: 252.681474ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:21.95212Z","caller":"traceutil/trace.go:171","msg":"trace[191228349] linearizableReadLoop","detail":"{readStateIndex:5454; appliedIndex:5452; }","duration":"204.517305ms","start":"2026-07-16T14:25:21.747587Z","end":"2026-07-16T14:25:21.952104Z","steps":["trace[191228349] 'read index received' (duration: 203.238774ms)","trace[191228349] 'applied index is now lower than readState.Index' (duration: 1.277311ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:25:21.952612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.386779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91ca8004-304a-412e-9d11-d9fd63aa8bf0\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-16T14:25:21.952761Z","caller":"traceutil/trace.go:171","msg":"trace[1732490825] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91ca8004-304a-412e-9d11-d9fd63aa8bf0; range_end:; response_count:1; response_revision:5259; }","duration":"205.574021ms","start":"2026-07-16T14:25:21.747131Z","end":"2026-07-16T14:25:21.952705Z","steps":["trace[1732490825] 'agreement among raft nodes before linearized reading' (duration: 205.058623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:21.952936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.978577ms","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-16T14:25:21.953019Z","caller":"traceutil/trace.go:171","msg":"trace[276310482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5259; }","duration":"237.127389ms","start":"2026-07-16T14:25:21.715874Z","end":"2026-07-16T14:25:21.953001Z","steps":["trace[276310482] 'agreement among raft nodes before linearized reading' (duration: 236.476109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:21.953379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.241292ms","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-16T14:25:21.953503Z","caller":"traceutil/trace.go:171","msg":"trace[1545856785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5259; }","duration":"109.435555ms","start":"2026-07-16T14:25:21.844049Z","end":"2026-07-16T14:25:21.953485Z","steps":["trace[1545856785] 'agreement among raft nodes before linearized reading' (duration: 108.722874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:23.202027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.12609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60660"} {"level":"info","ts":"2026-07-16T14:25:23.202462Z","caller":"traceutil/trace.go:171","msg":"trace[555402179] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5300; }","duration":"209.585448ms","start":"2026-07-16T14:25:22.992851Z","end":"2026-07-16T14:25:23.202436Z","steps":["trace[555402179] 'range keys from in-memory index tree' (duration: 208.682023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:23.203425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.901017ms","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-16T14:25:23.203549Z","caller":"traceutil/trace.go:171","msg":"trace[1687289785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5300; }","duration":"113.049189ms","start":"2026-07-16T14:25:23.090482Z","end":"2026-07-16T14:25:23.203532Z","steps":["trace[1687289785] 'range keys from in-memory index tree' (duration: 112.660433ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:25.620638Z","caller":"traceutil/trace.go:171","msg":"trace[82020233] linearizableReadLoop","detail":"{readStateIndex:5548; appliedIndex:5547; }","duration":"382.954974ms","start":"2026-07-16T14:25:25.237663Z","end":"2026-07-16T14:25:25.620618Z","steps":["trace[82020233] 'read index received' (duration: 382.813382ms)","trace[82020233] 'applied index is now lower than readState.Index' (duration: 140.432µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:25:25.620887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.199178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-wq2x5.18c2cb4a7e7e99c7\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-07-16T14:25:25.620898Z","caller":"traceutil/trace.go:171","msg":"trace[997855000] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"402.023475ms","start":"2026-07-16T14:25:25.218828Z","end":"2026-07-16T14:25:25.620852Z","steps":["trace[997855000] 'process raft request' (duration: 401.662869ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:25.620945Z","caller":"traceutil/trace.go:171","msg":"trace[448418389] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-wq2x5.18c2cb4a7e7e99c7; range_end:; response_count:1; response_revision:5348; }","duration":"383.28925ms","start":"2026-07-16T14:25:25.237637Z","end":"2026-07-16T14:25:25.620927Z","steps":["trace[448418389] 'agreement among raft nodes before linearized reading' (duration: 383.065277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:25.621039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:25.237627Z","time spent":"383.398522ms","remote":"127.0.0.1:54880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":984,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-wq2x5.18c2cb4a7e7e99c7\" "} {"level":"warn","ts":"2026-07-16T14:25:25.621154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:25.218813Z","time spent":"402.148728ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:25:28.629987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971871369213817,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T14:25:29.130089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971871369213817,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T14:25:29.131977Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.012559388s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T14:25:29.237624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.39461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:25:29.237826Z","caller":"traceutil/trace.go:171","msg":"trace[957935402] linearizableReadLoop","detail":"{readStateIndex:5579; appliedIndex:5577; }","duration":"1.10885059s","start":"2026-07-16T14:25:28.128956Z","end":"2026-07-16T14:25:29.237807Z","steps":["trace[957935402] 'read index received' (duration: 1.003196096s)","trace[957935402] 'applied index is now lower than readState.Index' (duration: 105.653424ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:25:29.237915Z","caller":"traceutil/trace.go:171","msg":"trace[756298543] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"1.118588747s","start":"2026-07-16T14:25:28.119316Z","end":"2026-07-16T14:25:29.237905Z","steps":["trace[756298543] 'process raft request' (duration: 1.012849533s)","trace[756298543] 'compare' (duration: 105.043803ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:25:29.237963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:28.119296Z","time spent":"1.118643169s","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1112,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T14:25:29.23861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.919556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T14:25:29.238687Z","caller":"traceutil/trace.go:171","msg":"trace[1702831955] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5378; }","duration":"895.045678ms","start":"2026-07-16T14:25:28.343624Z","end":"2026-07-16T14:25:29.23867Z","steps":["trace[1702831955] 'agreement among raft nodes before linearized reading' (duration: 894.872485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:29.238734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:28.343604Z","time spent":"895.11925ms","remote":"127.0.0.1:55140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":30,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-16T14:25:29.238822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.977758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:25:29.23887Z","caller":"traceutil/trace.go:171","msg":"trace[330195719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5378; }","duration":"589.031499ms","start":"2026-07-16T14:25:28.649827Z","end":"2026-07-16T14:25:29.238859Z","steps":["trace[330195719] 'agreement among raft nodes before linearized reading' (duration: 588.954418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:29.238858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.019153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-16T14:25:29.238907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:28.649771Z","time spent":"589.12874ms","remote":"127.0.0.1:54792","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-16T14:25:29.238927Z","caller":"traceutil/trace.go:171","msg":"trace[736024331] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5378; }","duration":"593.115476ms","start":"2026-07-16T14:25:28.645791Z","end":"2026-07-16T14:25:29.238907Z","steps":["trace[736024331] 'agreement among raft nodes before linearized reading' (duration: 593.013994ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:29.239045Z","caller":"traceutil/trace.go:171","msg":"trace[345344954] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"643.360192ms","start":"2026-07-16T14:25:28.595671Z","end":"2026-07-16T14:25:29.239031Z","steps":["trace[345344954] 'process raft request' (duration: 642.04979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:29.239017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.266175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-07-16T14:25:29.239134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:28.595632Z","time spent":"643.448602ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:25:29.239199Z","caller":"traceutil/trace.go:171","msg":"trace[39723138] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5378; }","duration":"235.488519ms","start":"2026-07-16T14:25:29.003621Z","end":"2026-07-16T14:25:29.23911Z","steps":["trace[39723138] 'agreement among raft nodes before linearized reading' (duration: 234.898959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:29.239376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110408635s","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-16T14:25:29.239429Z","caller":"traceutil/trace.go:171","msg":"trace[1993033548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5378; }","duration":"1.110492357s","start":"2026-07-16T14:25:28.128924Z","end":"2026-07-16T14:25:29.239416Z","steps":["trace[1993033548] 'agreement among raft nodes before linearized reading' (duration: 1.110241483s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:29.239467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:28.128904Z","time spent":"1.110556518s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T14:25:29.238988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:25:28.645769Z","time spent":"593.203607ms","remote":"127.0.0.1:55066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-07-16T14:25:29.440386Z","caller":"traceutil/trace.go:171","msg":"trace[643925572] linearizableReadLoop","detail":"{readStateIndex:5583; appliedIndex:5582; }","duration":"104.969232ms","start":"2026-07-16T14:25:29.335392Z","end":"2026-07-16T14:25:29.440361Z","steps":["trace[643925572] 'read index received' (duration: 44.641764ms)","trace[643925572] 'applied index is now lower than readState.Index' (duration: 60.326548ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:25:29.440529Z","caller":"traceutil/trace.go:171","msg":"trace[2026058042] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"117.372282ms","start":"2026-07-16T14:25:29.323132Z","end":"2026-07-16T14:25:29.440505Z","steps":["trace[2026058042] 'process raft request' (duration: 56.938513ms)","trace[2026058042] 'compare' (duration: 60.143335ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:25:29.440546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.131805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:25:29.440584Z","caller":"traceutil/trace.go:171","msg":"trace[972631814] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:5381; }","duration":"105.218916ms","start":"2026-07-16T14:25:29.335355Z","end":"2026-07-16T14:25:29.440574Z","steps":["trace[972631814] 'agreement among raft nodes before linearized reading' (duration: 105.131644ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:30.410291Z","caller":"traceutil/trace.go:171","msg":"trace[934882985] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"292.420395ms","start":"2026-07-16T14:25:30.117846Z","end":"2026-07-16T14:25:30.410267Z","steps":["trace[934882985] 'process raft request' (duration: 292.037529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:36.865645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.899738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T14:25:36.866269Z","caller":"traceutil/trace.go:171","msg":"trace[501096578] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5470; }","duration":"117.540058ms","start":"2026-07-16T14:25:36.7487Z","end":"2026-07-16T14:25:36.86624Z","steps":["trace[501096578] 'count revisions from in-memory index tree' (duration: 116.834897ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:37.692928Z","caller":"traceutil/trace.go:171","msg":"trace[796628839] linearizableReadLoop","detail":"{readStateIndex:5679; appliedIndex:5678; }","duration":"178.968681ms","start":"2026-07-16T14:25:37.513932Z","end":"2026-07-16T14:25:37.692901Z","steps":["trace[796628839] 'read index received' (duration: 178.778208ms)","trace[796628839] 'applied index is now lower than readState.Index' (duration: 189.053µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:25:37.693114Z","caller":"traceutil/trace.go:171","msg":"trace[1206649227] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"291.349423ms","start":"2026-07-16T14:25:37.401722Z","end":"2026-07-16T14:25:37.693071Z","steps":["trace[1206649227] 'process raft request' (duration: 290.966627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:37.693375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.414568ms","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-16T14:25:37.693503Z","caller":"traceutil/trace.go:171","msg":"trace[708857619] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5473; }","duration":"179.56332ms","start":"2026-07-16T14:25:37.51392Z","end":"2026-07-16T14:25:37.693483Z","steps":["trace[708857619] 'agreement among raft nodes before linearized reading' (duration: 179.347807ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:37.962868Z","caller":"traceutil/trace.go:171","msg":"trace[1908549166] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"214.769915ms","start":"2026-07-16T14:25:37.74806Z","end":"2026-07-16T14:25:37.962829Z","steps":["trace[1908549166] 'process raft request' (duration: 214.4729ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:37.9948Z","caller":"traceutil/trace.go:171","msg":"trace[1232411792] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"215.591488ms","start":"2026-07-16T14:25:37.779172Z","end":"2026-07-16T14:25:37.994764Z","steps":["trace[1232411792] 'process raft request' (duration: 197.893934ms)","trace[1232411792] 'compare' (duration: 17.44942ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:25:38.194659Z","caller":"traceutil/trace.go:171","msg":"trace[440941428] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"132.177417ms","start":"2026-07-16T14:25:38.062456Z","end":"2026-07-16T14:25:38.194634Z","steps":["trace[440941428] 'process raft request' (duration: 132.099606ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:38.194688Z","caller":"traceutil/trace.go:171","msg":"trace[1797571132] linearizableReadLoop","detail":"{readStateIndex:5693; appliedIndex:5690; }","duration":"125.352638ms","start":"2026-07-16T14:25:38.069318Z","end":"2026-07-16T14:25:38.194671Z","steps":["trace[1797571132] 'read index received' (duration: 79.439952ms)","trace[1797571132] 'applied index is now lower than readState.Index' (duration: 45.911846ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:25:38.194778Z","caller":"traceutil/trace.go:171","msg":"trace[542428808] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"131.932223ms","start":"2026-07-16T14:25:38.062836Z","end":"2026-07-16T14:25:38.194769Z","steps":["trace[542428808] 'process raft request' (duration: 131.767311ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:25:38.194682Z","caller":"traceutil/trace.go:171","msg":"trace[595094286] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"133.292815ms","start":"2026-07-16T14:25:38.06135Z","end":"2026-07-16T14:25:38.194643Z","steps":["trace[595094286] 'process raft request' (duration: 87.439331ms)","trace[595094286] 'compare' (duration: 45.636311ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:25:38.194853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.351044ms","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-16T14:25:38.194903Z","caller":"traceutil/trace.go:171","msg":"trace[1482423605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5487; }","duration":"121.440545ms","start":"2026-07-16T14:25:38.073449Z","end":"2026-07-16T14:25:38.19489Z","steps":["trace[1482423605] 'agreement among raft nodes before linearized reading' (duration: 121.353734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:38.194992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.674703ms","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-16T14:25:38.195031Z","caller":"traceutil/trace.go:171","msg":"trace[42804929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5487; }","duration":"125.756894ms","start":"2026-07-16T14:25:38.069265Z","end":"2026-07-16T14:25:38.195022Z","steps":["trace[42804929] 'agreement among raft nodes before linearized reading' (duration: 125.654443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:25:38.195173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.119371ms","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-16T14:25:38.195214Z","caller":"traceutil/trace.go:171","msg":"trace[916750339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5487; }","duration":"121.162251ms","start":"2026-07-16T14:25:38.074042Z","end":"2026-07-16T14:25:38.195204Z","steps":["trace[916750339] 'agreement among raft nodes before linearized reading' (duration: 121.09496ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:26:11.984625Z","caller":"traceutil/trace.go:171","msg":"trace[1449931256] linearizableReadLoop","detail":"{readStateIndex:6007; appliedIndex:6006; }","duration":"102.678422ms","start":"2026-07-16T14:26:11.881929Z","end":"2026-07-16T14:26:11.984607Z","steps":["trace[1449931256] 'read index received' (duration: 54.347968ms)","trace[1449931256] 'applied index is now lower than readState.Index' (duration: 48.329464ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:26:11.984731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.802424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:26:11.984753Z","caller":"traceutil/trace.go:171","msg":"trace[1048022759] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5793; }","duration":"102.882526ms","start":"2026-07-16T14:26:11.881862Z","end":"2026-07-16T14:26:11.984745Z","steps":["trace[1048022759] 'agreement among raft nodes before linearized reading' (duration: 102.833185ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:29:59.371067Z","caller":"traceutil/trace.go:171","msg":"trace[793317668] transaction","detail":"{read_only:false; response_revision:7346; number_of_response:1; }","duration":"157.359496ms","start":"2026-07-16T14:29:59.213688Z","end":"2026-07-16T14:29:59.371048Z","steps":["trace[793317668] 'process raft request' (duration: 157.254103ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:29:59.439392Z","caller":"traceutil/trace.go:171","msg":"trace[1734576565] linearizableReadLoop","detail":"{readStateIndex:7617; appliedIndex:7614; }","duration":"206.391626ms","start":"2026-07-16T14:29:59.232975Z","end":"2026-07-16T14:29:59.439367Z","steps":["trace[1734576565] 'read index received' (duration: 137.949752ms)","trace[1734576565] 'applied index is now lower than readState.Index' (duration: 68.441214ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:29:59.439581Z","caller":"traceutil/trace.go:171","msg":"trace[1807300975] transaction","detail":"{read_only:false; response_revision:7348; number_of_response:1; }","duration":"222.333986ms","start":"2026-07-16T14:29:59.217225Z","end":"2026-07-16T14:29:59.439559Z","steps":["trace[1807300975] 'process raft request' (duration: 222.114839ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:29:59.439436Z","caller":"traceutil/trace.go:171","msg":"trace[1985065535] transaction","detail":"{read_only:false; response_revision:7347; number_of_response:1; }","duration":"222.205232ms","start":"2026-07-16T14:29:59.217194Z","end":"2026-07-16T14:29:59.439399Z","steps":["trace[1985065535] 'process raft request' (duration: 222.021006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:29:59.441657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.645709ms","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-16T14:29:59.441717Z","caller":"traceutil/trace.go:171","msg":"trace[2125051241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7348; }","duration":"208.764133ms","start":"2026-07-16T14:29:59.232937Z","end":"2026-07-16T14:29:59.441701Z","steps":["trace[2125051241] 'agreement among raft nodes before linearized reading' (duration: 206.83807ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:30:08.824715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4991} {"level":"info","ts":"2026-07-16T14:30:08.960911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4991,"took":"133.653006ms","hash":1851683243,"current-db-size-bytes":50470912,"current-db-size":"50 MB","current-db-size-in-use-bytes":22142976,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-16T14:30:08.961001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1851683243,"revision":4991,"compact-revision":2779}