{"level":"warn","ts":"2026-07-17T21:38:54.312483Z","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-17T21:38:54.312583Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-17T21:38:54.312662Z","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-17T21:38:54.312675Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-17T21:38:54.312696Z","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-17T21:38:54.313531Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-07-17T21:38:54.313639Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-17T21:38:54.318826Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.92304ms"} {"level":"info","ts":"2026-07-17T21:38:54.331943Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-07-17T21:38:54.332065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T21:38:54.332095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-07-17T21:38:54.33213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T21:38:54.332147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-07-17T21:38:54.332206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-07-17T21:38:54.340471Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T21:38:54.342145Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T21:38:54.344089Z","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-17T21:38:54.346384Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T21:38:54.346573Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T21:38:54.346626Z","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-17T21:38:54.347285Z","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-17T21:38:54.347305Z","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-17T21:38:54.348605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-07-17T21:38:54.348832Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-17T21:38:54.357909Z","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-17T21:38:54.358049Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-17T21:38:54.358097Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-17T21:38:54.358327Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T21:38:54.358383Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T21:38:54.532923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T21:38:54.532997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T21:38:54.533028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-07-17T21:38:54.533047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-07-17T21:38:54.533059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-17T21:38:54.533081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-07-17T21:38:54.533095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-17T21:38:54.534397Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T21:38:54.535386Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T21:38:54.535382Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T21:38:54.535473Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T21:38:54.535807Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T21:38:54.535845Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T21:38:54.536775Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T21:38:54.536873Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T21:38:54.536908Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T21:38:54.540853Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-07-17T21:38:54.541202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T21:39:08.743079Z","caller":"traceutil/trace.go:171","msg":"trace[582767077] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"109.202342ms","start":"2026-07-17T21:39:08.633839Z","end":"2026-07-17T21:39:08.743042Z","steps":["trace[582767077] 'read index received' (duration: 108.995543ms)","trace[582767077] 'applied index is now lower than readState.Index' (duration: 205.679µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:39:08.74351Z","caller":"traceutil/trace.go:171","msg":"trace[58287393] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"304.217396ms","start":"2026-07-17T21:39:08.439278Z","end":"2026-07-17T21:39:08.743495Z","steps":["trace[58287393] 'process raft request' (duration: 303.599389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:08.743579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.712505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T21:39:08.743742Z","caller":"traceutil/trace.go:171","msg":"trace[275650448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:362; }","duration":"109.938985ms","start":"2026-07-17T21:39:08.633779Z","end":"2026-07-17T21:39:08.743718Z","steps":["trace[275650448] 'agreement among raft nodes before linearized reading' (duration: 109.657723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:08.744116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:08.439261Z","time spent":"304.286639ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:39:09.300093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.173896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:39:09.300397Z","caller":"traceutil/trace.go:171","msg":"trace[152334618] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"550.404864ms","start":"2026-07-17T21:39:08.749967Z","end":"2026-07-17T21:39:09.300372Z","steps":["trace[152334618] 'process raft request' (duration: 550.333191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:09.300477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:08.749956Z","time spent":"550.482917ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:39:09.300517Z","caller":"traceutil/trace.go:171","msg":"trace[435562977] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"550.815383ms","start":"2026-07-17T21:39:08.74968Z","end":"2026-07-17T21:39:09.300496Z","steps":["trace[435562977] 'process raft request' (duration: 550.564771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:09.30062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:08.749649Z","time spent":"550.917398ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:39:09.30063Z","caller":"traceutil/trace.go:171","msg":"trace[817377718] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"550.998651ms","start":"2026-07-17T21:39:08.749562Z","end":"2026-07-17T21:39:09.300561Z","steps":["trace[817377718] 'process raft request' (duration: 120.987828ms)","trace[817377718] 'compare' (duration: 428.981336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:39:09.300945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:08.749532Z","time spent":"551.225521ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:39:33.478267Z","caller":"traceutil/trace.go:171","msg":"trace[237156634] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"294.674278ms","start":"2026-07-17T21:39:33.183565Z","end":"2026-07-17T21:39:33.478239Z","steps":["trace[237156634] 'process raft request' (duration: 288.397509ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:33.478325Z","caller":"traceutil/trace.go:171","msg":"trace[1786618063] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"277.379669ms","start":"2026-07-17T21:39:33.200902Z","end":"2026-07-17T21:39:33.478282Z","steps":["trace[1786618063] 'process raft request' (duration: 277.073229ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:33.478312Z","caller":"traceutil/trace.go:171","msg":"trace[1162146930] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"257.225931ms","start":"2026-07-17T21:39:33.221041Z","end":"2026-07-17T21:39:33.478267Z","steps":["trace[1162146930] 'process raft request' (duration: 257.04623ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:39.402397Z","caller":"traceutil/trace.go:171","msg":"trace[1032287733] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"149.280245ms","start":"2026-07-17T21:39:39.253093Z","end":"2026-07-17T21:39:39.402373Z","steps":["trace[1032287733] 'process raft request' (duration: 148.749913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:39.834994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.012482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:39:39.83511Z","caller":"traceutil/trace.go:171","msg":"trace[1481029242] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"318.47778ms","start":"2026-07-17T21:39:39.516616Z","end":"2026-07-17T21:39:39.835094Z","steps":["trace[1481029242] 'read index received' (duration: 200.210398ms)","trace[1481029242] 'applied index is now lower than readState.Index' (duration: 118.266262ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:39:39.835195Z","caller":"traceutil/trace.go:171","msg":"trace[1285879894] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"380.801316ms","start":"2026-07-17T21:39:39.454367Z","end":"2026-07-17T21:39:39.835169Z","steps":["trace[1285879894] 'process raft request' (duration: 262.545554ms)","trace[1285879894] 'compare' (duration: 117.83269ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:39:39.835309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:39.454344Z","time spent":"380.914976ms","remote":"127.0.0.1:44072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T21:39:39.835322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.69868ms","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-17T21:39:39.835364Z","caller":"traceutil/trace.go:171","msg":"trace[1538077819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"318.7718ms","start":"2026-07-17T21:39:39.516581Z","end":"2026-07-17T21:39:39.835353Z","steps":["trace[1538077819] 'agreement among raft nodes before linearized reading' (duration: 318.61533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:39.83543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:39.516567Z","time spent":"318.85304ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T21:39:39.835484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.685563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T21:39:39.835504Z","caller":"traceutil/trace.go:171","msg":"trace[1871252849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"310.752443ms","start":"2026-07-17T21:39:39.524745Z","end":"2026-07-17T21:39:39.835498Z","steps":["trace[1871252849] 'agreement among raft nodes before linearized reading' (duration: 310.654163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:39.835518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:39.524705Z","time spent":"310.810553ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T21:39:39.835582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.548992ms","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-17T21:39:39.835594Z","caller":"traceutil/trace.go:171","msg":"trace[206835934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"305.559842ms","start":"2026-07-17T21:39:39.53003Z","end":"2026-07-17T21:39:39.83559Z","steps":["trace[206835934] 'agreement among raft nodes before linearized reading' (duration: 305.522362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:39.835605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:39.529994Z","time spent":"305.608822ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T21:39:40.479037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.949947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:39:40.479199Z","caller":"traceutil/trace.go:171","msg":"trace[1495228980] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"630.961847ms","start":"2026-07-17T21:39:39.848221Z","end":"2026-07-17T21:39:40.479183Z","steps":["trace[1495228980] 'read index received' (duration: 42.107906ms)","trace[1495228980] 'applied index is now lower than readState.Index' (duration: 588.852871ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:39:40.479416Z","caller":"traceutil/trace.go:171","msg":"trace[907101270] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"639.224424ms","start":"2026-07-17T21:39:39.840179Z","end":"2026-07-17T21:39:40.479404Z","steps":["trace[907101270] 'process raft request' (duration: 166.804606ms)","trace[907101270] 'compare' (duration: 471.567235ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:39:40.479697Z","caller":"traceutil/trace.go:171","msg":"trace[1558924446] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"639.388614ms","start":"2026-07-17T21:39:39.840288Z","end":"2026-07-17T21:39:40.479676Z","steps":["trace[1558924446] 'process raft request' (duration: 638.841183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:40.479737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.340216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T21:39:40.479742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.489478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:39:40.479785Z","caller":"traceutil/trace.go:171","msg":"trace[1194245379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"429.431527ms","start":"2026-07-17T21:39:40.050337Z","end":"2026-07-17T21:39:40.479769Z","steps":["trace[1194245379] 'agreement among raft nodes before linearized reading' (duration: 429.355817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:40.47977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:39.840253Z","time spent":"639.483354ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:39:40.479824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:40.050308Z","time spent":"429.507627ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T21:39:40.479827Z","caller":"traceutil/trace.go:171","msg":"trace[1736698609] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:597; }","duration":"631.610838ms","start":"2026-07-17T21:39:39.848197Z","end":"2026-07-17T21:39:40.479808Z","steps":["trace[1736698609] 'agreement among raft nodes before linearized reading' (duration: 631.457298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:40.479802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.587691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7mhbf\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-17T21:39:40.479876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:39.848188Z","time spent":"631.678768ms","remote":"127.0.0.1:43764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-17T21:39:40.479921Z","caller":"traceutil/trace.go:171","msg":"trace[872594891] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7mhbf; range_end:; response_count:1; response_revision:597; }","duration":"613.774321ms","start":"2026-07-17T21:39:39.866119Z","end":"2026-07-17T21:39:40.479894Z","steps":["trace[872594891] 'agreement among raft nodes before linearized reading' (duration: 613.40041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:40.479988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:39.866105Z","time spent":"613.872431ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-7mhbf\" "} {"level":"warn","ts":"2026-07-17T21:39:40.480199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:39.840162Z","time spent":"639.290864ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:39:41.447435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115764726137151,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T21:39:41.846445Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.15277784s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-17T21:39:42.510329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.313037ms","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-17T21:39:42.510453Z","caller":"traceutil/trace.go:171","msg":"trace[1015619205] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:597; }","duration":"643.447658ms","start":"2026-07-17T21:39:41.866983Z","end":"2026-07-17T21:39:42.510431Z","steps":["trace[1015619205] 'range keys from in-memory index tree' (duration: 643.295146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:42.510783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.870109524s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:39:42.510964Z","caller":"traceutil/trace.go:171","msg":"trace[59637770] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.817405936s","start":"2026-07-17T21:39:40.693541Z","end":"2026-07-17T21:39:42.510947Z","steps":["trace[59637770] 'process raft request' (duration: 1.817342435s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:42.511026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:40.693512Z","time spent":"1.817484696s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:39:42.511099Z","caller":"traceutil/trace.go:171","msg":"trace[125558387] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.012976859s","start":"2026-07-17T21:39:40.498105Z","end":"2026-07-17T21:39:42.511082Z","steps":["trace[125558387] 'process raft request' (duration: 142.493214ms)","trace[125558387] 'compare' (duration: 1.869530911s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:39:42.5112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:40.498074Z","time spent":"2.013061349s","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:39:42.622629Z","caller":"traceutil/trace.go:171","msg":"trace[1902981271] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:613; }","duration":"1.675343651s","start":"2026-07-17T21:39:40.947233Z","end":"2026-07-17T21:39:42.622577Z","steps":["trace[1902981271] 'read index received' (duration: 899.491747ms)","trace[1902981271] 'applied index is now lower than readState.Index' (duration: 775.850804ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:39:42.622669Z","caller":"traceutil/trace.go:171","msg":"trace[1393380348] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.721977292s","start":"2026-07-17T21:39:40.900666Z","end":"2026-07-17T21:39:42.622643Z","steps":["trace[1393380348] 'process raft request' (duration: 1.721291489s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:42.622777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:40.900621Z","time spent":"1.722098793s","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:39:42.622966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.675708922s","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-17T21:39:42.62297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.523762ms","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-17T21:39:42.623056Z","caller":"traceutil/trace.go:171","msg":"trace[1149867705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"138.653832ms","start":"2026-07-17T21:39:42.484378Z","end":"2026-07-17T21:39:42.623032Z","steps":["trace[1149867705] 'agreement among raft nodes before linearized reading' (duration: 138.421351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:42.623058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.767118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T21:39:42.623016Z","caller":"traceutil/trace.go:171","msg":"trace[2067165147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"1.675812432s","start":"2026-07-17T21:39:40.947189Z","end":"2026-07-17T21:39:42.623002Z","steps":["trace[2067165147] 'agreement among raft nodes before linearized reading' (duration: 1.675540621s)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:42.623105Z","caller":"traceutil/trace.go:171","msg":"trace[1203789724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"137.815888ms","start":"2026-07-17T21:39:42.485278Z","end":"2026-07-17T21:39:42.623094Z","steps":["trace[1203789724] 'agreement among raft nodes before linearized reading' (duration: 137.676198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:42.623174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:40.947167Z","time spent":"1.675960362s","remote":"127.0.0.1:43782","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-17T21:39:42.623205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.351758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:39:42.623231Z","caller":"traceutil/trace.go:171","msg":"trace[532320221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"573.377778ms","start":"2026-07-17T21:39:42.049845Z","end":"2026-07-17T21:39:42.623222Z","steps":["trace[532320221] 'agreement among raft nodes before linearized reading' (duration: 573.336208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:42.623257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:42.049808Z","time spent":"573.442508ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T21:39:42.623688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.735027ms","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-17T21:39:42.623835Z","caller":"traceutil/trace.go:171","msg":"trace[1460330861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"729.955699ms","start":"2026-07-17T21:39:41.893845Z","end":"2026-07-17T21:39:42.6238Z","steps":["trace[1460330861] 'agreement among raft nodes before linearized reading' (duration: 728.934404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:42.623948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:41.89382Z","time spent":"730.112759ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T21:39:43.103695Z","caller":"traceutil/trace.go:171","msg":"trace[690174781] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"140.414216ms","start":"2026-07-17T21:39:42.963234Z","end":"2026-07-17T21:39:43.103648Z","steps":["trace[690174781] 'process raft request' (duration: 140.259556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:44.487992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.107377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T21:39:44.488026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.972889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:39:44.488104Z","caller":"traceutil/trace.go:171","msg":"trace[1239255591] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"348.261207ms","start":"2026-07-17T21:39:44.139813Z","end":"2026-07-17T21:39:44.488074Z","steps":["trace[1239255591] 'range keys from in-memory index tree' (duration: 347.900756ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:44.488108Z","caller":"traceutil/trace.go:171","msg":"trace[743586404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"437.071919ms","start":"2026-07-17T21:39:44.051014Z","end":"2026-07-17T21:39:44.488086Z","steps":["trace[743586404] 'range keys from in-memory index tree' (duration: 436.880998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:44.488177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:44.13979Z","time spent":"348.371327ms","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-07-17T21:39:44.488222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:44.050949Z","time spent":"437.26199ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T21:39:44.493736Z","caller":"traceutil/trace.go:171","msg":"trace[1859740811] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"148.281782ms","start":"2026-07-17T21:39:44.345437Z","end":"2026-07-17T21:39:44.493719Z","steps":["trace[1859740811] 'process raft request' (duration: 148.061952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:47.223697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.934674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:39:47.223773Z","caller":"traceutil/trace.go:171","msg":"trace[1696887374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"174.059724ms","start":"2026-07-17T21:39:47.049696Z","end":"2026-07-17T21:39:47.223755Z","steps":["trace[1696887374] 'range keys from in-memory index tree' (duration: 173.829884ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:54.851708Z","caller":"traceutil/trace.go:171","msg":"trace[1748138962] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"134.334181ms","start":"2026-07-17T21:39:54.717349Z","end":"2026-07-17T21:39:54.851683Z","steps":["trace[1748138962] 'process raft request' (duration: 134.186009ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:54.851804Z","caller":"traceutil/trace.go:171","msg":"trace[1198864023] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:765; }","duration":"134.122239ms","start":"2026-07-17T21:39:54.717653Z","end":"2026-07-17T21:39:54.851775Z","steps":["trace[1198864023] 'read index received' (duration: 134.107839ms)","trace[1198864023] 'applied index is now lower than readState.Index' (duration: 12.14µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:39:54.852242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.548673ms","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-17T21:39:54.852296Z","caller":"traceutil/trace.go:171","msg":"trace[495580273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:745; }","duration":"134.653484ms","start":"2026-07-17T21:39:54.717629Z","end":"2026-07-17T21:39:54.852282Z","steps":["trace[495580273] 'agreement among raft nodes before linearized reading' (duration: 134.262301ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:54.853395Z","caller":"traceutil/trace.go:171","msg":"trace[1136124818] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"132.966777ms","start":"2026-07-17T21:39:54.720006Z","end":"2026-07-17T21:39:54.852973Z","steps":["trace[1136124818] 'process raft request' (duration: 132.929387ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:54.853856Z","caller":"traceutil/trace.go:171","msg":"trace[67563039] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"134.975667ms","start":"2026-07-17T21:39:54.718811Z","end":"2026-07-17T21:39:54.853787Z","steps":["trace[67563039] 'process raft request' (duration: 134.056659ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:58.052278Z","caller":"traceutil/trace.go:171","msg":"trace[761349802] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"110.712261ms","start":"2026-07-17T21:39:57.941536Z","end":"2026-07-17T21:39:58.052248Z","steps":["trace[761349802] 'read index received' (duration: 110.345757ms)","trace[761349802] 'applied index is now lower than readState.Index' (duration: 365.174µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:39:58.052609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.045374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T21:39:58.052675Z","caller":"traceutil/trace.go:171","msg":"trace[416947957] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:763; }","duration":"111.167335ms","start":"2026-07-17T21:39:57.941491Z","end":"2026-07-17T21:39:58.052658Z","steps":["trace[416947957] 'agreement among raft nodes before linearized reading' (duration: 110.934833ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:39:58.053446Z","caller":"traceutil/trace.go:171","msg":"trace[148911977] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"451.48366ms","start":"2026-07-17T21:39:57.601001Z","end":"2026-07-17T21:39:58.052485Z","steps":["trace[148911977] 'process raft request' (duration: 451.074546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:39:58.053761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:39:57.600973Z","time spent":"452.605744ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:40:29.251513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115764726138195,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T21:40:29.451316Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.004985918s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T21:40:29.451704Z","caller":"traceutil/trace.go:171","msg":"trace[1198117007] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"1.00550159s","start":"2026-07-17T21:40:28.446175Z","end":"2026-07-17T21:40:29.451677Z","steps":["trace[1198117007] 'process raft request' (duration: 1.005336287s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:40:29.451862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:40:28.446136Z","time spent":"1.005646613s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:40:29.454279Z","caller":"traceutil/trace.go:171","msg":"trace[1495982912] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1195; }","duration":"703.124333ms","start":"2026-07-17T21:40:28.751121Z","end":"2026-07-17T21:40:29.454245Z","steps":["trace[1495982912] 'read index received' (duration: 700.395222ms)","trace[1495982912] 'applied index is now lower than readState.Index' (duration: 2.727871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:40:29.45453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.982407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T21:40:29.454456Z","caller":"traceutil/trace.go:171","msg":"trace[1022506568] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"324.680249ms","start":"2026-07-17T21:40:29.129704Z","end":"2026-07-17T21:40:29.454384Z","steps":["trace[1022506568] 'process raft request' (duration: 324.441324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:40:29.454565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.139703ms","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-17T21:40:29.454621Z","caller":"traceutil/trace.go:171","msg":"trace[1578721062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1168; }","duration":"426.270126ms","start":"2026-07-17T21:40:29.028335Z","end":"2026-07-17T21:40:29.454606Z","steps":["trace[1578721062] 'agreement among raft nodes before linearized reading' (duration: 426.045091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:40:29.454667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.19953ms","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-17T21:40:29.454581Z","caller":"traceutil/trace.go:171","msg":"trace[1121079281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1168; }","duration":"429.069499ms","start":"2026-07-17T21:40:29.025498Z","end":"2026-07-17T21:40:29.454568Z","steps":["trace[1121079281] 'agreement among raft nodes before linearized reading' (duration: 428.885295ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:40:29.4547Z","caller":"traceutil/trace.go:171","msg":"trace[1779640472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1168; }","duration":"428.251231ms","start":"2026-07-17T21:40:29.026439Z","end":"2026-07-17T21:40:29.45469Z","steps":["trace[1779640472] 'agreement among raft nodes before linearized reading' (duration: 428.163909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:40:29.454692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.33541ms","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-17T21:40:29.454733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:40:29.026426Z","time spent":"428.298462ms","remote":"127.0.0.1:43782","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-17T21:40:29.454771Z","caller":"traceutil/trace.go:171","msg":"trace[937173041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1168; }","duration":"427.455893ms","start":"2026-07-17T21:40:29.027293Z","end":"2026-07-17T21:40:29.454749Z","steps":["trace[937173041] 'agreement among raft nodes before linearized reading' (duration: 427.253799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:40:29.454772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.639104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T21:40:29.454821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:40:29.027273Z","time spent":"427.539244ms","remote":"127.0.0.1:43782","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":"info","ts":"2026-07-17T21:40:29.45483Z","caller":"traceutil/trace.go:171","msg":"trace[184938512] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1168; }","duration":"703.741386ms","start":"2026-07-17T21:40:28.751075Z","end":"2026-07-17T21:40:29.454816Z","steps":["trace[184938512] 'agreement among raft nodes before linearized reading' (duration: 703.643264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:40:29.454668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:40:29.028306Z","time spent":"426.353288ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T21:40:29.454852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:40:29.129671Z","time spent":"324.948265ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:40:29.454881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:40:28.751053Z","time spent":"703.813759ms","remote":"127.0.0.1:43956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-17T21:40:29.454702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:40:29.025478Z","time spent":"429.217442ms","remote":"127.0.0.1:43782","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-17T21:40:46.342688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.732317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:40:46.342792Z","caller":"traceutil/trace.go:171","msg":"trace[544513955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"291.908861ms","start":"2026-07-17T21:40:46.050862Z","end":"2026-07-17T21:40:46.342771Z","steps":["trace[544513955] 'range keys from in-memory index tree' (duration: 291.587173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:40:46.343356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.310779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-6mdx7\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-07-17T21:40:46.34349Z","caller":"traceutil/trace.go:171","msg":"trace[1638467466] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-6mdx7; range_end:; response_count:1; response_revision:1310; }","duration":"145.514404ms","start":"2026-07-17T21:40:46.197945Z","end":"2026-07-17T21:40:46.343459Z","steps":["trace[1638467466] 'range keys from in-memory index tree' (duration: 144.946468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:40:52.147334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.34246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1827"} {"level":"info","ts":"2026-07-17T21:40:52.147428Z","caller":"traceutil/trace.go:171","msg":"trace[1199121622] range","detail":"{range_begin:/registry/secrets/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1428; }","duration":"152.472705ms","start":"2026-07-17T21:40:51.994936Z","end":"2026-07-17T21:40:52.147409Z","steps":["trace[1199121622] 'range keys from in-memory index tree' (duration: 152.175626ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:40:52.453947Z","caller":"traceutil/trace.go:171","msg":"trace[664710869] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1467; }","duration":"197.421507ms","start":"2026-07-17T21:40:52.256506Z","end":"2026-07-17T21:40:52.453928Z","steps":["trace[664710869] 'read index received' (duration: 197.261533ms)","trace[664710869] 'applied index is now lower than readState.Index' (duration: 159.324µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:40:52.454032Z","caller":"traceutil/trace.go:171","msg":"trace[1444108278] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"210.155199ms","start":"2026-07-17T21:40:52.243869Z","end":"2026-07-17T21:40:52.454024Z","steps":["trace[1444108278] 'process raft request' (duration: 209.960712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:40:52.454266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.713556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T21:40:52.454371Z","caller":"traceutil/trace.go:171","msg":"trace[860039193] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1431; }","duration":"197.89684ms","start":"2026-07-17T21:40:52.256452Z","end":"2026-07-17T21:40:52.454349Z","steps":["trace[860039193] 'agreement among raft nodes before linearized reading' (duration: 197.703165ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:40:54.698298Z","caller":"traceutil/trace.go:171","msg":"trace[1358908257] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"227.612483ms","start":"2026-07-17T21:40:54.470663Z","end":"2026-07-17T21:40:54.698276Z","steps":["trace[1358908257] 'process raft request' (duration: 227.47885ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:09.265279Z","caller":"traceutil/trace.go:171","msg":"trace[841394552] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1698; }","duration":"154.979886ms","start":"2026-07-17T21:41:09.110277Z","end":"2026-07-17T21:41:09.265257Z","steps":["trace[841394552] 'process raft request' (duration: 154.77195ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:12.844908Z","caller":"traceutil/trace.go:171","msg":"trace[1400371350] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"241.267149ms","start":"2026-07-17T21:41:12.603618Z","end":"2026-07-17T21:41:12.844885Z","steps":["trace[1400371350] 'process raft request' (duration: 241.129375ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:12.857674Z","caller":"traceutil/trace.go:171","msg":"trace[1757441820] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"156.666644ms","start":"2026-07-17T21:41:12.700986Z","end":"2026-07-17T21:41:12.857652Z","steps":["trace[1757441820] 'process raft request' (duration: 156.126148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.222571Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115764726139576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T21:41:20.723254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115764726139576,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T21:41:20.809582Z","caller":"traceutil/trace.go:171","msg":"trace[632155847] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"1.156753477s","start":"2026-07-17T21:41:19.652786Z","end":"2026-07-17T21:41:20.80954Z","steps":["trace[632155847] 'process raft request' (duration: 1.156445648s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.809887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:19.652765Z","time spent":"1.156922423s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:20.852923Z","caller":"traceutil/trace.go:171","msg":"trace[241483154] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1914; }","duration":"1.131088419s","start":"2026-07-17T21:41:19.721812Z","end":"2026-07-17T21:41:20.8529Z","steps":["trace[241483154] 'read index received' (duration: 1.0874004s)","trace[241483154] 'applied index is now lower than readState.Index' (duration: 43.686949ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:41:20.852988Z","caller":"traceutil/trace.go:171","msg":"trace[2096158409] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"488.852511ms","start":"2026-07-17T21:41:20.364103Z","end":"2026-07-17T21:41:20.852955Z","steps":["trace[2096158409] 'process raft request' (duration: 488.631814ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:20.853043Z","caller":"traceutil/trace.go:171","msg":"trace[1853057163] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"165.903958ms","start":"2026-07-17T21:41:20.687128Z","end":"2026-07-17T21:41:20.853032Z","steps":["trace[1853057163] 'process raft request' (duration: 165.736772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.853167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.364067Z","time spent":"488.996346ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:41:20.853296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104392793s","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-17T21:41:20.853304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.706397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:41:20.853353Z","caller":"traceutil/trace.go:171","msg":"trace[2099532875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1852; }","duration":"1.104502977s","start":"2026-07-17T21:41:19.748833Z","end":"2026-07-17T21:41:20.853336Z","steps":["trace[2099532875] 'agreement among raft nodes before linearized reading' (duration: 1.104339662s)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:20.853381Z","caller":"traceutil/trace.go:171","msg":"trace[738291034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1852; }","duration":"802.848622ms","start":"2026-07-17T21:41:20.050516Z","end":"2026-07-17T21:41:20.853364Z","steps":["trace[738291034] 'agreement among raft nodes before linearized reading' (duration: 802.701047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.853389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.709405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c331a8b9868eeb\" ","response":"range_response_count:1 size:982"} {"level":"warn","ts":"2026-07-17T21:41:20.853392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:19.748803Z","time spent":"1.10458066s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T21:41:20.853404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092131575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-17T21:41:20.85346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.050491Z","time spent":"802.961665ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T21:41:20.853463Z","caller":"traceutil/trace.go:171","msg":"trace[1515571812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1852; }","duration":"1.092248829s","start":"2026-07-17T21:41:19.7612Z","end":"2026-07-17T21:41:20.853449Z","steps":["trace[1515571812] 'agreement among raft nodes before linearized reading' (duration: 1.092022222s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.8535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:19.761168Z","time spent":"1.092323611s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T21:41:20.853529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104001772s","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-17T21:41:20.853566Z","caller":"traceutil/trace.go:171","msg":"trace[1154583524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"1.104048763s","start":"2026-07-17T21:41:19.749503Z","end":"2026-07-17T21:41:20.853552Z","steps":["trace[1154583524] 'agreement among raft nodes before linearized reading' (duration: 1.10396414s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.853592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:19.749496Z","time spent":"1.104090444s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T21:41:20.853589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.217419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-36adc6aa-c95e-4d58-82ba-33683a51dfcf\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-17T21:41:20.853632Z","caller":"traceutil/trace.go:171","msg":"trace[1577549307] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-36adc6aa-c95e-4d58-82ba-33683a51dfcf; range_end:; response_count:1; response_revision:1852; }","duration":"464.287491ms","start":"2026-07-17T21:41:20.389333Z","end":"2026-07-17T21:41:20.85362Z","steps":["trace[1577549307] 'agreement among raft nodes before linearized reading' (duration: 464.134116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.85365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.571318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-36adc6aa-c95e-4d58-82ba-33683a51dfcf\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-17T21:41:20.853686Z","caller":"traceutil/trace.go:171","msg":"trace[554614135] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-36adc6aa-c95e-4d58-82ba-33683a51dfcf; range_end:; response_count:1; response_revision:1852; }","duration":"700.63337ms","start":"2026-07-17T21:41:20.153047Z","end":"2026-07-17T21:41:20.85368Z","steps":["trace[554614135] 'agreement among raft nodes before linearized reading' (duration: 700.544197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.853697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.38932Z","time spent":"464.362183ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-36adc6aa-c95e-4d58-82ba-33683a51dfcf\" "} {"level":"info","ts":"2026-07-17T21:41:20.853423Z","caller":"traceutil/trace.go:171","msg":"trace[873437454] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c331a8b9868eeb; range_end:; response_count:1; response_revision:1852; }","duration":"892.805568ms","start":"2026-07-17T21:41:19.960607Z","end":"2026-07-17T21:41:20.853412Z","steps":["trace[873437454] 'agreement among raft nodes before linearized reading' (duration: 892.677264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.853681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.844715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-17T21:41:20.853757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:19.960577Z","time spent":"893.16844ms","remote":"127.0.0.1:43618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1005,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c331a8b9868eeb\" "} {"level":"info","ts":"2026-07-17T21:41:20.853794Z","caller":"traceutil/trace.go:171","msg":"trace[458060647] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1852; }","duration":"160.070322ms","start":"2026-07-17T21:41:20.693698Z","end":"2026-07-17T21:41:20.853769Z","steps":["trace[458060647] 'agreement among raft nodes before linearized reading' (duration: 159.602167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.853797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131995169s","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-17T21:41:20.85371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.153035Z","time spent":"700.669471ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-36adc6aa-c95e-4d58-82ba-33683a51dfcf\" "} {"level":"info","ts":"2026-07-17T21:41:20.853833Z","caller":"traceutil/trace.go:171","msg":"trace[6013001] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"1.132076391s","start":"2026-07-17T21:41:19.721746Z","end":"2026-07-17T21:41:20.853822Z","steps":["trace[6013001] 'agreement among raft nodes before linearized reading' (duration: 1.131917746s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.853878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:19.721721Z","time spent":"1.132150364s","remote":"127.0.0.1:59892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-17T21:41:20.854514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097889507s","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-17T21:41:20.854595Z","caller":"traceutil/trace.go:171","msg":"trace[1610018371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1852; }","duration":"1.097988921s","start":"2026-07-17T21:41:19.756596Z","end":"2026-07-17T21:41:20.854585Z","steps":["trace[1610018371] 'agreement among raft nodes before linearized reading' (duration: 1.097772703s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:20.855477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:19.75658Z","time spent":"1.098818757s","remote":"127.0.0.1:43782","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-17T21:41:21.388356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115764726139583,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T21:41:22.040299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.080313ms","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-17T21:41:22.04043Z","caller":"traceutil/trace.go:171","msg":"trace[32584816] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1853; }","duration":"174.225258ms","start":"2026-07-17T21:41:21.86619Z","end":"2026-07-17T21:41:22.040416Z","steps":["trace[32584816] 'range keys from in-memory index tree' (duration: 174.066453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.041367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.139885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:41:22.041809Z","caller":"traceutil/trace.go:171","msg":"trace[1637515616] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"1.18322273s","start":"2026-07-17T21:41:20.858552Z","end":"2026-07-17T21:41:22.041775Z","steps":["trace[1637515616] 'process raft request' (duration: 967.512078ms)","trace[1637515616] 'compare' (duration: 214.129422ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:41:22.041955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.858526Z","time spent":"1.183345453s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:22.04263Z","caller":"traceutil/trace.go:171","msg":"trace[1108185585] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"1.182809048s","start":"2026-07-17T21:41:20.859772Z","end":"2026-07-17T21:41:22.042581Z","steps":["trace[1108185585] 'process raft request' (duration: 1.182474377s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.042819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.85976Z","time spent":"1.182954242s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:22.042976Z","caller":"traceutil/trace.go:171","msg":"trace[872271003] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"1.183082756s","start":"2026-07-17T21:41:20.859881Z","end":"2026-07-17T21:41:22.042964Z","steps":["trace[872271003] 'process raft request' (duration: 1.182545659s)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:22.04294Z","caller":"traceutil/trace.go:171","msg":"trace[2053217636] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"1.182862069s","start":"2026-07-17T21:41:20.860044Z","end":"2026-07-17T21:41:22.042906Z","steps":["trace[2053217636] 'process raft request' (duration: 1.182456946s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.043028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.859862Z","time spent":"1.183136648s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:41:22.043141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.860025Z","time spent":"1.183013264s","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:22.043462Z","caller":"traceutil/trace.go:171","msg":"trace[726979776] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"1.181854077s","start":"2026-07-17T21:41:20.861595Z","end":"2026-07-17T21:41:22.043449Z","steps":["trace[726979776] 'process raft request' (duration: 1.181179895s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.043526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.861585Z","time spent":"1.181914428s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:22.043672Z","caller":"traceutil/trace.go:171","msg":"trace[407194689] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"1.163940668s","start":"2026-07-17T21:41:20.879726Z","end":"2026-07-17T21:41:22.043667Z","steps":["trace[407194689] 'process raft request' (duration: 1.16371513s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.043705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.87971Z","time spent":"1.163977038s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:22.043566Z","caller":"traceutil/trace.go:171","msg":"trace[1468826661] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"1.16750998s","start":"2026-07-17T21:41:20.876047Z","end":"2026-07-17T21:41:22.043557Z","steps":["trace[1468826661] 'process raft request' (duration: 1.167320364s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.043777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.87602Z","time spent":"1.167739787s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:22.04397Z","caller":"traceutil/trace.go:171","msg":"trace[1553062786] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"862.555368ms","start":"2026-07-17T21:41:21.181408Z","end":"2026-07-17T21:41:22.043963Z","steps":["trace[1553062786] 'process raft request' (duration: 862.116264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.044004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:21.181383Z","time spent":"862.600289ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:22.044057Z","caller":"traceutil/trace.go:171","msg":"trace[1112184082] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1918; }","duration":"1.155772657s","start":"2026-07-17T21:41:20.888271Z","end":"2026-07-17T21:41:22.044044Z","steps":["trace[1112184082] 'read index received' (duration: 937.783541ms)","trace[1112184082] 'applied index is now lower than readState.Index' (duration: 217.987896ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:41:22.044185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155916982s","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-17T21:41:22.044205Z","caller":"traceutil/trace.go:171","msg":"trace[1748314432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1862; }","duration":"1.155977593s","start":"2026-07-17T21:41:20.888221Z","end":"2026-07-17T21:41:22.044199Z","steps":["trace[1748314432] 'agreement among raft nodes before linearized reading' (duration: 1.155900991s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.044219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.8882Z","time spent":"1.156015795s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T21:41:22.044157Z","caller":"traceutil/trace.go:171","msg":"trace[277936556] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"578.211928ms","start":"2026-07-17T21:41:21.465919Z","end":"2026-07-17T21:41:22.04413Z","steps":["trace[277936556] 'process raft request' (duration: 578.040253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.044357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142737672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T21:41:22.044389Z","caller":"traceutil/trace.go:171","msg":"trace[781385303] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1862; }","duration":"1.142812825s","start":"2026-07-17T21:41:20.901567Z","end":"2026-07-17T21:41:22.04438Z","steps":["trace[781385303] 'agreement among raft nodes before linearized reading' (duration: 1.142724692s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.044418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:20.901544Z","time spent":"1.142865217s","remote":"127.0.0.1:60910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-07-17T21:41:22.044431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.546031ms","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-17T21:41:22.044454Z","caller":"traceutil/trace.go:171","msg":"trace[2142176884] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"181.581663ms","start":"2026-07-17T21:41:21.862864Z","end":"2026-07-17T21:41:22.044446Z","steps":["trace[2142176884] 'agreement among raft nodes before linearized reading' (duration: 181.520421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:22.044531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.976886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-36adc6aa-c95e-4d58-82ba-33683a51dfcf\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-07-17T21:41:22.044551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:21.465884Z","time spent":"578.486337ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:22.044588Z","caller":"traceutil/trace.go:171","msg":"trace[1525601525] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-36adc6aa-c95e-4d58-82ba-33683a51dfcf; range_end:; response_count:1; response_revision:1862; }","duration":"182.055108ms","start":"2026-07-17T21:41:21.862516Z","end":"2026-07-17T21:41:22.044571Z","steps":["trace[1525601525] 'agreement among raft nodes before linearized reading' (duration: 181.950445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:35.609685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.603094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:41:35.609801Z","caller":"traceutil/trace.go:171","msg":"trace[1512742165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1970; }","duration":"557.7758ms","start":"2026-07-17T21:41:35.051997Z","end":"2026-07-17T21:41:35.609773Z","steps":["trace[1512742165] 'range keys from in-memory index tree' (duration: 557.48947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:35.609847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:35.051968Z","time spent":"557.870383ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T21:41:35.609969Z","caller":"traceutil/trace.go:171","msg":"trace[550361084] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"475.567753ms","start":"2026-07-17T21:41:35.134366Z","end":"2026-07-17T21:41:35.609934Z","steps":["trace[550361084] 'read index received' (duration: 474.781126ms)","trace[550361084] 'applied index is now lower than readState.Index' (duration: 784.897µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:41:35.610108Z","caller":"traceutil/trace.go:171","msg":"trace[6655172] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"480.053742ms","start":"2026-07-17T21:41:35.130042Z","end":"2026-07-17T21:41:35.610096Z","steps":["trace[6655172] 'process raft request' (duration: 479.09318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:35.610329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.950125ms","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-17T21:41:35.610378Z","caller":"traceutil/trace.go:171","msg":"trace[133781079] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1971; }","duration":"476.012187ms","start":"2026-07-17T21:41:35.134344Z","end":"2026-07-17T21:41:35.610356Z","steps":["trace[133781079] 'agreement among raft nodes before linearized reading' (duration: 475.823861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:35.610407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:35.134333Z","time spent":"476.067719ms","remote":"127.0.0.1:59892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-17T21:41:35.612295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:35.130004Z","time spent":"480.129844ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:41:51.614521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.815199ms","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":"warn","ts":"2026-07-17T21:41:51.614568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.321532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:41:51.614644Z","caller":"traceutil/trace.go:171","msg":"trace[1578056420] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2076; }","duration":"200.984976ms","start":"2026-07-17T21:41:51.413633Z","end":"2026-07-17T21:41:51.614617Z","steps":["trace[1578056420] 'count revisions from in-memory index tree' (duration: 200.676805ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:51.614659Z","caller":"traceutil/trace.go:171","msg":"trace[585689710] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2076; }","duration":"467.495448ms","start":"2026-07-17T21:41:51.147141Z","end":"2026-07-17T21:41:51.614636Z","steps":["trace[585689710] 'count revisions from in-memory index tree' (duration: 467.159827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:51.614723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:51.147113Z","time spent":"467.594812ms","remote":"127.0.0.1:43820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-17T21:41:51.614787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.549469ms","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-17T21:41:51.615001Z","caller":"traceutil/trace.go:171","msg":"trace[1917171218] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2076; }","duration":"124.86926ms","start":"2026-07-17T21:41:51.490105Z","end":"2026-07-17T21:41:51.614974Z","steps":["trace[1917171218] 'count revisions from in-memory index tree' (duration: 124.416074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:57.426901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.996076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:41:57.426995Z","caller":"traceutil/trace.go:171","msg":"trace[1538874869] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2107; }","duration":"318.147102ms","start":"2026-07-17T21:41:57.108824Z","end":"2026-07-17T21:41:57.426971Z","steps":["trace[1538874869] 'count revisions from in-memory index tree' (duration: 317.875193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:57.427051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:57.108807Z","time spent":"318.230035ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-07-17T21:41:58.667979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.733918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:41:58.668427Z","caller":"traceutil/trace.go:171","msg":"trace[464761502] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"460.101085ms","start":"2026-07-17T21:41:58.208303Z","end":"2026-07-17T21:41:58.668404Z","steps":["trace[464761502] 'process raft request' (duration: 103.808089ms)","trace[464761502] 'compare' (duration: 354.879657ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:41:58.668605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:58.208275Z","time spent":"460.270702ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:58.668689Z","caller":"traceutil/trace.go:171","msg":"trace[864566689] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2182; }","duration":"424.208541ms","start":"2026-07-17T21:41:58.244452Z","end":"2026-07-17T21:41:58.668661Z","steps":["trace[864566689] 'read index received' (duration: 67.551011ms)","trace[864566689] 'applied index is now lower than readState.Index' (duration: 356.65464ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:41:58.668696Z","caller":"traceutil/trace.go:171","msg":"trace[1142969388] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"451.421181ms","start":"2026-07-17T21:41:58.217233Z","end":"2026-07-17T21:41:58.668654Z","steps":["trace[1142969388] 'process raft request' (duration: 450.85174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:58.669414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.247573ms","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-17T21:41:58.669485Z","caller":"traceutil/trace.go:171","msg":"trace[465996283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2112; }","duration":"424.357396ms","start":"2026-07-17T21:41:58.245115Z","end":"2026-07-17T21:41:58.669473Z","steps":["trace[465996283] 'agreement among raft nodes before linearized reading' (duration: 424.192211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:58.669543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:58.245099Z","time spent":"424.434029ms","remote":"127.0.0.1:43782","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":"warn","ts":"2026-07-17T21:41:58.669581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.59449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-17T21:41:58.669593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.204469ms","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-17T21:41:58.669641Z","caller":"traceutil/trace.go:171","msg":"trace[281752532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2112; }","duration":"406.657402ms","start":"2026-07-17T21:41:58.262959Z","end":"2026-07-17T21:41:58.669616Z","steps":["trace[281752532] 'agreement among raft nodes before linearized reading' (duration: 406.496227ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:58.669663Z","caller":"traceutil/trace.go:171","msg":"trace[1370069413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2112; }","duration":"397.285771ms","start":"2026-07-17T21:41:58.272358Z","end":"2026-07-17T21:41:58.669644Z","steps":["trace[1370069413] 'agreement among raft nodes before linearized reading' (duration: 397.049113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:58.669683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:58.262951Z","time spent":"406.724664ms","remote":"127.0.0.1:43782","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-17T21:41:58.669707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:58.27235Z","time spent":"397.348224ms","remote":"127.0.0.1:43782","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":"warn","ts":"2026-07-17T21:41:58.669885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.020463ms","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-17T21:41:58.66992Z","caller":"traceutil/trace.go:171","msg":"trace[1564748356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2112; }","duration":"401.080016ms","start":"2026-07-17T21:41:58.26883Z","end":"2026-07-17T21:41:58.66991Z","steps":["trace[1564748356] 'agreement among raft nodes before linearized reading' (duration: 400.984352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:58.66995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:58.26882Z","time spent":"401.124299ms","remote":"127.0.0.1:43782","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-17T21:41:58.670193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.052684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-17T21:41:58.669407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:58.217217Z","time spent":"452.062654ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:41:58.670316Z","caller":"traceutil/trace.go:171","msg":"trace[1821041419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2112; }","duration":"412.162887ms","start":"2026-07-17T21:41:58.258108Z","end":"2026-07-17T21:41:58.67027Z","steps":["trace[1821041419] 'agreement among raft nodes before linearized reading' (duration: 411.781653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:58.67038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:58.258098Z","time spent":"412.270451ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T21:41:58.668868Z","caller":"traceutil/trace.go:171","msg":"trace[195197698] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"175.370402ms","start":"2026-07-17T21:41:58.493481Z","end":"2026-07-17T21:41:58.668222Z","steps":["trace[195197698] 'process raft request' (duration: 174.675757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:58.668797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.341816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T21:41:58.6702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.760087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c331afc9b94c26\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-17T21:41:58.670505Z","caller":"traceutil/trace.go:171","msg":"trace[486876713] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c331afc9b94c26; range_end:; response_count:1; response_revision:2112; }","duration":"224.103368ms","start":"2026-07-17T21:41:58.446386Z","end":"2026-07-17T21:41:58.670489Z","steps":["trace[486876713] 'agreement among raft nodes before linearized reading' (duration: 223.724885ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:58.670481Z","caller":"traceutil/trace.go:171","msg":"trace[1739436737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2112; }","duration":"426.056586ms","start":"2026-07-17T21:41:58.244414Z","end":"2026-07-17T21:41:58.670471Z","steps":["trace[1739436737] 'agreement among raft nodes before linearized reading' (duration: 424.317235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:58.670623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:58.244399Z","time spent":"426.213182ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T21:41:58.837762Z","caller":"traceutil/trace.go:171","msg":"trace[801673024] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2185; }","duration":"158.188786ms","start":"2026-07-17T21:41:58.679549Z","end":"2026-07-17T21:41:58.837738Z","steps":["trace[801673024] 'read index received' (duration: 41.056428ms)","trace[801673024] 'applied index is now lower than readState.Index' (duration: 117.131448ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:41:58.838078Z","caller":"traceutil/trace.go:171","msg":"trace[243894470] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"161.157951ms","start":"2026-07-17T21:41:58.6769Z","end":"2026-07-17T21:41:58.838057Z","steps":["trace[243894470] 'process raft request' (duration: 127.95203ms)","trace[243894470] 'compare' (duration: 32.378701ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:41:58.838357Z","caller":"traceutil/trace.go:171","msg":"trace[1686674056] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"161.338796ms","start":"2026-07-17T21:41:58.677002Z","end":"2026-07-17T21:41:58.838341Z","steps":["trace[1686674056] 'process raft request' (duration: 160.59466ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:58.838567Z","caller":"traceutil/trace.go:171","msg":"trace[1637431499] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"161.515963ms","start":"2026-07-17T21:41:58.677032Z","end":"2026-07-17T21:41:58.838548Z","steps":["trace[1637431499] 'process raft request' (duration: 160.6108ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:58.839656Z","caller":"traceutil/trace.go:171","msg":"trace[617579728] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"162.57143ms","start":"2026-07-17T21:41:58.677065Z","end":"2026-07-17T21:41:58.839636Z","steps":["trace[617579728] 'process raft request' (duration: 160.60803ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:58.839965Z","caller":"traceutil/trace.go:171","msg":"trace[814439971] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"162.752986ms","start":"2026-07-17T21:41:58.67718Z","end":"2026-07-17T21:41:58.839933Z","steps":["trace[814439971] 'process raft request' (duration: 160.526297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:58.840129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.564459ms","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-17T21:41:58.840169Z","caller":"traceutil/trace.go:171","msg":"trace[406039604] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2118; }","duration":"160.633612ms","start":"2026-07-17T21:41:58.679526Z","end":"2026-07-17T21:41:58.840159Z","steps":["trace[406039604] 'agreement among raft nodes before linearized reading' (duration: 160.550009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:59.809013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.135239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:41:59.809114Z","caller":"traceutil/trace.go:171","msg":"trace[1890878880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2118; }","duration":"758.290145ms","start":"2026-07-17T21:41:59.050801Z","end":"2026-07-17T21:41:59.809091Z","steps":["trace[1890878880] 'range keys from in-memory index tree' (duration: 758.065048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:41:59.80915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:59.050782Z","time spent":"758.360157ms","remote":"127.0.0.1:43580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T21:41:59.809848Z","caller":"traceutil/trace.go:171","msg":"trace[592944803] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"246.685371ms","start":"2026-07-17T21:41:59.56315Z","end":"2026-07-17T21:41:59.809835Z","steps":["trace[592944803] 'read index received' (duration: 214.741553ms)","trace[592944803] 'applied index is now lower than readState.Index' (duration: 31.942648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:41:59.809943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.794345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:41:59.809964Z","caller":"traceutil/trace.go:171","msg":"trace[2072152077] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2119; }","duration":"246.865437ms","start":"2026-07-17T21:41:59.563089Z","end":"2026-07-17T21:41:59.809955Z","steps":["trace[2072152077] 'agreement among raft nodes before linearized reading' (duration: 246.788295ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:41:59.810395Z","caller":"traceutil/trace.go:171","msg":"trace[1788812716] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"746.21015ms","start":"2026-07-17T21:41:59.064168Z","end":"2026-07-17T21:41:59.810378Z","steps":["trace[1788812716] 'process raft request' (duration: 713.867348ms)","trace[1788812716] 'compare' (duration: 31.666948ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:41:59.810541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:41:59.064146Z","time spent":"746.286832ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:42:00.499057Z","caller":"traceutil/trace.go:171","msg":"trace[354147333] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"263.599974ms","start":"2026-07-17T21:42:00.235429Z","end":"2026-07-17T21:42:00.499028Z","steps":["trace[354147333] 'process raft request' (duration: 263.453829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:42:01.345131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115764726140156,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T21:42:01.70154Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.023726158s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T21:42:01.703355Z","caller":"traceutil/trace.go:171","msg":"trace[340736683] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"1.025670658s","start":"2026-07-17T21:42:00.67766Z","end":"2026-07-17T21:42:01.70333Z","steps":["trace[340736683] 'process raft request' (duration: 1.024542198s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:42:01.703511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:00.677628Z","time spent":"1.025797162s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:42:02.299825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.261512ms","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-17T21:42:02.299968Z","caller":"traceutil/trace.go:171","msg":"trace[32252603] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2121; }","duration":"433.417207ms","start":"2026-07-17T21:42:01.86653Z","end":"2026-07-17T21:42:02.299947Z","steps":["trace[32252603] 'range keys from in-memory index tree' (duration: 433.230551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:42:02.300664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.643029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:42:02.300879Z","caller":"traceutil/trace.go:171","msg":"trace[1918782544] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2193; }","duration":"1.456566377s","start":"2026-07-17T21:42:00.844296Z","end":"2026-07-17T21:42:02.300862Z","steps":["trace[1918782544] 'read index received' (duration: 857.425276ms)","trace[1918782544] 'applied index is now lower than readState.Index' (duration: 599.140031ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:42:02.300959Z","caller":"traceutil/trace.go:171","msg":"trace[691394555] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"1.62260124s","start":"2026-07-17T21:42:00.678347Z","end":"2026-07-17T21:42:02.300948Z","steps":["trace[691394555] 'process raft request' (duration: 1.081584618s)","trace[691394555] 'compare' (duration: 539.676984ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:42:02.301018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:00.678317Z","time spent":"1.622667372s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:42:02.301144Z","caller":"traceutil/trace.go:171","msg":"trace[1149270387] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"666.307772ms","start":"2026-07-17T21:42:01.634825Z","end":"2026-07-17T21:42:02.301133Z","steps":["trace[1149270387] 'process raft request' (duration: 665.933019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:42:02.301237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:01.634793Z","time spent":"666.385104ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:42:02.301287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456989472s","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-17T21:42:02.301326Z","caller":"traceutil/trace.go:171","msg":"trace[1390522528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2123; }","duration":"1.457067055s","start":"2026-07-17T21:42:00.844248Z","end":"2026-07-17T21:42:02.301315Z","steps":["trace[1390522528] 'agreement among raft nodes before linearized reading' (duration: 1.456906949s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:42:02.301373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:00.844202Z","time spent":"1.457160078s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T21:42:03.13983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.174379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:42:03.140074Z","caller":"traceutil/trace.go:171","msg":"trace[1774514814] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"839.128188ms","start":"2026-07-17T21:42:02.300916Z","end":"2026-07-17T21:42:03.140044Z","steps":["trace[1774514814] 'read index received' (duration: 210.625997ms)","trace[1774514814] 'applied index is now lower than readState.Index' (duration: 628.47017ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:42:03.140106Z","caller":"traceutil/trace.go:171","msg":"trace[1264489231] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"834.12555ms","start":"2026-07-17T21:42:02.305961Z","end":"2026-07-17T21:42:03.140087Z","steps":["trace[1264489231] 'process raft request' (duration: 833.978325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:42:03.140181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:02.305934Z","time spent":"834.212034ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:42:03.140187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.286584173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:42:03.140307Z","caller":"traceutil/trace.go:171","msg":"trace[1902728027] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2125; }","duration":"2.286741518s","start":"2026-07-17T21:42:00.853536Z","end":"2026-07-17T21:42:03.140278Z","steps":["trace[1902728027] 'agreement among raft nodes before linearized reading' (duration: 2.286487929s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:42:03.140405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:00.853529Z","time spent":"2.286864042s","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-17T21:42:03.140773Z","caller":"traceutil/trace.go:171","msg":"trace[1719747986] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"635.314213ms","start":"2026-07-17T21:42:02.50544Z","end":"2026-07-17T21:42:03.140754Z","steps":["trace[1719747986] 'process raft request' (duration: 634.602017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:42:03.140756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.295647064s","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-17T21:42:03.140878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:02.505412Z","time spent":"635.398856ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:42:03.140888Z","caller":"traceutil/trace.go:171","msg":"trace[92087166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2126; }","duration":"2.29582648s","start":"2026-07-17T21:42:00.845032Z","end":"2026-07-17T21:42:03.140859Z","steps":["trace[92087166] 'agreement among raft nodes before linearized reading' (duration: 2.295100004s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:42:03.140958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:00.84502Z","time spent":"2.295917923s","remote":"127.0.0.1:43782","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":"info","ts":"2026-07-17T21:42:03.140043Z","caller":"traceutil/trace.go:171","msg":"trace[418172575] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"1.322252317s","start":"2026-07-17T21:42:01.817683Z","end":"2026-07-17T21:42:03.139935Z","steps":["trace[418172575] 'process raft request' (duration: 693.884481ms)","trace[418172575] 'compare' (duration: 627.566477ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:42:03.141278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:01.817631Z","time spent":"1.323601825s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:42:03.193546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.348162211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T21:42:03.193617Z","caller":"traceutil/trace.go:171","msg":"trace[708056441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2126; }","duration":"2.348279115s","start":"2026-07-17T21:42:00.845319Z","end":"2026-07-17T21:42:03.193598Z","steps":["trace[708056441] 'agreement among raft nodes before linearized reading' (duration: 2.294825564s)","trace[708056441] 'range keys from in-memory index tree' (duration: 53.286555ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:42:03.193701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:00.845281Z","time spent":"2.348407709s","remote":"127.0.0.1:43782","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-17T21:42:03.193944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142939117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:42:03.194264Z","caller":"traceutil/trace.go:171","msg":"trace[647152157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2126; }","duration":"1.143344691s","start":"2026-07-17T21:42:02.050899Z","end":"2026-07-17T21:42:03.194243Z","steps":["trace[647152157] 'agreement among raft nodes before linearized reading' (duration: 1.089257418s)","trace[647152157] 'range keys from in-memory index tree' (duration: 53.716191ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:42:03.194321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:02.050857Z","time spent":"1.143450896s","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T21:42:03.193954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.348361779s","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-17T21:42:03.194473Z","caller":"traceutil/trace.go:171","msg":"trace[1968869680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2126; }","duration":"2.348943509s","start":"2026-07-17T21:42:00.845509Z","end":"2026-07-17T21:42:03.194453Z","steps":["trace[1968869680] 'agreement among raft nodes before linearized reading' (duration: 2.294565635s)","trace[1968869680] 'range keys from in-memory index tree' (duration: 53.612276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:42:03.194529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:00.845502Z","time spent":"2.349012151s","remote":"127.0.0.1:43782","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":"warn","ts":"2026-07-17T21:42:03.194063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.339589178s","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-17T21:42:03.194805Z","caller":"traceutil/trace.go:171","msg":"trace[119373488] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2126; }","duration":"2.340371276s","start":"2026-07-17T21:42:00.854419Z","end":"2026-07-17T21:42:03.19479Z","steps":["trace[119373488] 'agreement among raft nodes before linearized reading' (duration: 2.285743373s)","trace[119373488] 'range keys from in-memory index tree' (duration: 53.762293ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:42:03.194849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:00.854404Z","time spent":"2.340433017s","remote":"127.0.0.1:59892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-17T21:42:03.194182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.348550405s","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-17T21:42:03.195058Z","caller":"traceutil/trace.go:171","msg":"trace[1422921060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2126; }","duration":"2.349589182s","start":"2026-07-17T21:42:00.845435Z","end":"2026-07-17T21:42:03.195024Z","steps":["trace[1422921060] 'agreement among raft nodes before linearized reading' (duration: 2.29469237s)","trace[1422921060] 'range keys from in-memory index tree' (duration: 53.796164ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:42:03.195365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:42:00.845418Z","time spent":"2.349926284s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T21:43:07.422548Z","caller":"traceutil/trace.go:171","msg":"trace[2065815677] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"319.172976ms","start":"2026-07-17T21:43:07.103358Z","end":"2026-07-17T21:43:07.422531Z","steps":["trace[2065815677] 'process raft request' (duration: 319.077362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:07.422662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.103341Z","time spent":"319.265979ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:43:07.422677Z","caller":"traceutil/trace.go:171","msg":"trace[721958937] linearizableReadLoop","detail":"{readStateIndex:2661; appliedIndex:2661; }","duration":"154.753343ms","start":"2026-07-17T21:43:07.267897Z","end":"2026-07-17T21:43:07.422651Z","steps":["trace[721958937] 'read index received' (duration: 154.740803ms)","trace[721958937] 'applied index is now lower than readState.Index' (duration: 6.15µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:43:07.422825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.901109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:43:07.422863Z","caller":"traceutil/trace.go:171","msg":"trace[1510790237] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2573; }","duration":"154.990483ms","start":"2026-07-17T21:43:07.267862Z","end":"2026-07-17T21:43:07.422852Z","steps":["trace[1510790237] 'agreement among raft nodes before linearized reading' (duration: 154.889999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:07.922928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115764726141154,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T21:43:08.423472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115764726141154,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T21:43:08.555877Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.133396725s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T21:43:08.587938Z","caller":"traceutil/trace.go:171","msg":"trace[1298918450] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"1.167906409s","start":"2026-07-17T21:43:07.42Z","end":"2026-07-17T21:43:08.587907Z","steps":["trace[1298918450] 'process raft request' (duration: 1.13610921s)","trace[1298918450] 'compare' (duration: 31.235848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:43:08.588282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.419983Z","time spent":"1.168020902s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:43:08.60826Z","caller":"traceutil/trace.go:171","msg":"trace[511205085] transaction","detail":"{read_only:false; response_revision:2575; number_of_response:1; }","duration":"1.185852171s","start":"2026-07-17T21:43:07.422394Z","end":"2026-07-17T21:43:08.608246Z","steps":["trace[511205085] 'process raft request' (duration: 1.185650894s)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:08.608258Z","caller":"traceutil/trace.go:171","msg":"trace[114410841] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"669.52901ms","start":"2026-07-17T21:43:07.938704Z","end":"2026-07-17T21:43:08.608233Z","steps":["trace[114410841] 'process raft request' (duration: 669.453927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:08.608351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.422385Z","time spent":"1.185910014s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:43:08.608457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.938664Z","time spent":"669.646705ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:43:08.608462Z","caller":"traceutil/trace.go:171","msg":"trace[1075910166] linearizableReadLoop","detail":"{readStateIndex:2665; appliedIndex:2661; }","duration":"1.185706336s","start":"2026-07-17T21:43:07.422732Z","end":"2026-07-17T21:43:08.608439Z","steps":["trace[1075910166] 'read index received' (duration: 1.133379566s)","trace[1075910166] 'applied index is now lower than readState.Index' (duration: 52.32536ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:43:08.608603Z","caller":"traceutil/trace.go:171","msg":"trace[1167053147] transaction","detail":"{read_only:false; response_revision:2577; number_of_response:1; }","duration":"438.163609ms","start":"2026-07-17T21:43:08.170405Z","end":"2026-07-17T21:43:08.608569Z","steps":["trace[1167053147] 'process raft request' (duration: 437.792685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:08.608755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:08.17037Z","time spent":"438.306225ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:43:08.60882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.449221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T21:43:08.608859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250153466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-17T21:43:08.608881Z","caller":"traceutil/trace.go:171","msg":"trace[1840410332] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2577; }","duration":"935.538034ms","start":"2026-07-17T21:43:07.673328Z","end":"2026-07-17T21:43:08.608866Z","steps":["trace[1840410332] 'agreement among raft nodes before linearized reading' (duration: 935.451131ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:08.608897Z","caller":"traceutil/trace.go:171","msg":"trace[1222589141] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2577; }","duration":"1.250280841s","start":"2026-07-17T21:43:07.358606Z","end":"2026-07-17T21:43:08.608887Z","steps":["trace[1222589141] 'agreement among raft nodes before linearized reading' (duration: 1.250120655s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:08.608915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.673312Z","time spent":"935.594636ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-17T21:43:08.608928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.358589Z","time spent":"1.250332183s","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-17T21:43:08.60895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.221834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-07-17T21:43:08.608966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096286876s","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-17T21:43:08.60904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.995759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:43:08.609042Z","caller":"traceutil/trace.go:171","msg":"trace[1793597321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2577; }","duration":"1.096370459s","start":"2026-07-17T21:43:07.512652Z","end":"2026-07-17T21:43:08.609023Z","steps":["trace[1793597321] 'agreement among raft nodes before linearized reading' (duration: 1.09613961s)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:08.609075Z","caller":"traceutil/trace.go:171","msg":"trace[1838059256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2577; }","duration":"363.062482ms","start":"2026-07-17T21:43:08.246002Z","end":"2026-07-17T21:43:08.609064Z","steps":["trace[1838059256] 'agreement among raft nodes before linearized reading' (duration: 363.01346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:08.609089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.512566Z","time spent":"1.096514054s","remote":"127.0.0.1:43782","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-17T21:43:08.609106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:08.245986Z","time spent":"363.113934ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T21:43:08.609187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.112836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T21:43:08.609226Z","caller":"traceutil/trace.go:171","msg":"trace[830568525] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:2577; }","duration":"658.189028ms","start":"2026-07-17T21:43:07.951026Z","end":"2026-07-17T21:43:08.609215Z","steps":["trace[830568525] 'agreement among raft nodes before linearized reading' (duration: 658.047423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:08.609262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093032083s","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":"warn","ts":"2026-07-17T21:43:08.609278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091507795s","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-17T21:43:08.609282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.951013Z","time spent":"658.261692ms","remote":"127.0.0.1:43596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"info","ts":"2026-07-17T21:43:08.609297Z","caller":"traceutil/trace.go:171","msg":"trace[88067928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2577; }","duration":"1.093100585s","start":"2026-07-17T21:43:07.516187Z","end":"2026-07-17T21:43:08.609287Z","steps":["trace[88067928] 'agreement among raft nodes before linearized reading' (duration: 1.09299278s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:08.609307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096588138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T21:43:08.609317Z","caller":"traceutil/trace.go:171","msg":"trace[877749498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2577; }","duration":"1.091572758s","start":"2026-07-17T21:43:07.517734Z","end":"2026-07-17T21:43:08.609307Z","steps":["trace[877749498] 'agreement among raft nodes before linearized reading' (duration: 1.091466913s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:08.609332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.516172Z","time spent":"1.093153357s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T21:43:08.609338Z","caller":"traceutil/trace.go:171","msg":"trace[706112411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2577; }","duration":"1.09663768s","start":"2026-07-17T21:43:07.512692Z","end":"2026-07-17T21:43:08.609329Z","steps":["trace[706112411] 'agreement among raft nodes before linearized reading' (duration: 1.096553906s)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:08.608994Z","caller":"traceutil/trace.go:171","msg":"trace[686113080] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2577; }","duration":"386.315997ms","start":"2026-07-17T21:43:08.222664Z","end":"2026-07-17T21:43:08.60898Z","steps":["trace[686113080] 'agreement among raft nodes before linearized reading' (duration: 386.174471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:08.609347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.517722Z","time spent":"1.091618319s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T21:43:08.609368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.512686Z","time spent":"1.096676282s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T21:43:08.609379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:08.222639Z","time spent":"386.729953ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-17T21:43:08.609466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092489562s","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-17T21:43:08.609496Z","caller":"traceutil/trace.go:171","msg":"trace[343989447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2577; }","duration":"1.092549594s","start":"2026-07-17T21:43:07.516939Z","end":"2026-07-17T21:43:08.609488Z","steps":["trace[343989447] 'agreement among raft nodes before linearized reading' (duration: 1.092472892s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:08.60952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:07.516922Z","time spent":"1.092592656s","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T21:43:08.755449Z","caller":"traceutil/trace.go:171","msg":"trace[1698379270] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"141.910208ms","start":"2026-07-17T21:43:08.613509Z","end":"2026-07-17T21:43:08.75542Z","steps":["trace[1698379270] 'process raft request' (duration: 141.554044ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:08.755934Z","caller":"traceutil/trace.go:171","msg":"trace[1008705960] transaction","detail":"{read_only:false; response_revision:2579; number_of_response:1; }","duration":"139.394292ms","start":"2026-07-17T21:43:08.616499Z","end":"2026-07-17T21:43:08.755893Z","steps":["trace[1008705960] 'process raft request' (duration: 138.874103ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:08.75619Z","caller":"traceutil/trace.go:171","msg":"trace[2101470068] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"139.237977ms","start":"2026-07-17T21:43:08.616939Z","end":"2026-07-17T21:43:08.756177Z","steps":["trace[2101470068] 'process raft request' (duration: 139.024699ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:08.756153Z","caller":"traceutil/trace.go:171","msg":"trace[577128573] linearizableReadLoop","detail":"{readStateIndex:2667; appliedIndex:2666; }","duration":"141.837826ms","start":"2026-07-17T21:43:08.614282Z","end":"2026-07-17T21:43:08.75612Z","steps":["trace[577128573] 'read index received' (duration: 64.214303ms)","trace[577128573] 'applied index is now lower than readState.Index' (duration: 77.622093ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:43:08.756495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.178049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-17T21:43:08.756553Z","caller":"traceutil/trace.go:171","msg":"trace[771777244] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2583; }","duration":"142.309664ms","start":"2026-07-17T21:43:08.614228Z","end":"2026-07-17T21:43:08.756538Z","steps":["trace[771777244] 'agreement among raft nodes before linearized reading' (duration: 141.992752ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:08.756517Z","caller":"traceutil/trace.go:171","msg":"trace[679997445] transaction","detail":"{read_only:false; response_revision:2580; number_of_response:1; }","duration":"139.976135ms","start":"2026-07-17T21:43:08.616512Z","end":"2026-07-17T21:43:08.756488Z","steps":["trace[679997445] 'process raft request' (duration: 139.297478ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:08.756636Z","caller":"traceutil/trace.go:171","msg":"trace[135314687] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"138.287942ms","start":"2026-07-17T21:43:08.618333Z","end":"2026-07-17T21:43:08.756621Z","steps":["trace[135314687] 'process raft request' (duration: 137.72982ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:08.75669Z","caller":"traceutil/trace.go:171","msg":"trace[1584363753] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"139.268039ms","start":"2026-07-17T21:43:08.617384Z","end":"2026-07-17T21:43:08.756652Z","steps":["trace[1584363753] 'process raft request' (duration: 138.631434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:08.762513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.042842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-07-17T21:43:08.762725Z","caller":"traceutil/trace.go:171","msg":"trace[868697176] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2584; }","duration":"148.280531ms","start":"2026-07-17T21:43:08.61443Z","end":"2026-07-17T21:43:08.76271Z","steps":["trace[868697176] 'agreement among raft nodes before linearized reading' (duration: 147.923577ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:43:46.062232Z","caller":"traceutil/trace.go:171","msg":"trace[1473657914] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"395.408869ms","start":"2026-07-17T21:43:45.666787Z","end":"2026-07-17T21:43:46.062196Z","steps":["trace[1473657914] 'process raft request' (duration: 394.992623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:43:46.062424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:43:45.66675Z","time spent":"395.574577ms","remote":"127.0.0.1:43618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:44:45.469617Z","caller":"traceutil/trace.go:171","msg":"trace[1915646523] transaction","detail":"{read_only:false; response_revision:3094; number_of_response:1; }","duration":"221.731855ms","start":"2026-07-17T21:44:45.247853Z","end":"2026-07-17T21:44:45.469585Z","steps":["trace[1915646523] 'process raft request' (duration: 159.675401ms)","trace[1915646523] 'compare' (duration: 61.817443ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:44:45.46972Z","caller":"traceutil/trace.go:171","msg":"trace[894864074] linearizableReadLoop","detail":"{readStateIndex:3204; appliedIndex:3203; }","duration":"186.847396ms","start":"2026-07-17T21:44:45.282738Z","end":"2026-07-17T21:44:45.469586Z","steps":["trace[894864074] 'read index received' (duration: 124.782052ms)","trace[894864074] 'applied index is now lower than readState.Index' (duration: 62.064134ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:44:45.469917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.30241ms","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-17T21:44:45.46975Z","caller":"traceutil/trace.go:171","msg":"trace[1994104475] transaction","detail":"{read_only:false; response_revision:3095; number_of_response:1; }","duration":"182.863352ms","start":"2026-07-17T21:44:45.286863Z","end":"2026-07-17T21:44:45.469727Z","steps":["trace[1994104475] 'process raft request' (duration: 182.673591ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:44:45.47Z","caller":"traceutil/trace.go:171","msg":"trace[217395247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3095; }","duration":"199.455901ms","start":"2026-07-17T21:44:45.270529Z","end":"2026-07-17T21:44:45.469985Z","steps":["trace[217395247] 'agreement among raft nodes before linearized reading' (duration: 199.140119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:44:45.470094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.929391ms","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-17T21:44:45.470123Z","caller":"traceutil/trace.go:171","msg":"trace[2064398144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3095; }","duration":"182.974452ms","start":"2026-07-17T21:44:45.287142Z","end":"2026-07-17T21:44:45.470116Z","steps":["trace[2064398144] 'agreement among raft nodes before linearized reading' (duration: 182.849591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:44:45.470334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.20982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-17T21:44:45.470402Z","caller":"traceutil/trace.go:171","msg":"trace[857144602] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3095; }","duration":"111.291361ms","start":"2026-07-17T21:44:45.359094Z","end":"2026-07-17T21:44:45.470386Z","steps":["trace[857144602] 'agreement among raft nodes before linearized reading' (duration: 110.913588ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:45:01.472389Z","caller":"traceutil/trace.go:171","msg":"trace[875983119] linearizableReadLoop","detail":"{readStateIndex:3292; appliedIndex:3291; }","duration":"105.481654ms","start":"2026-07-17T21:45:01.36687Z","end":"2026-07-17T21:45:01.472352Z","steps":["trace[875983119] 'read index received' (duration: 105.215631ms)","trace[875983119] 'applied index is now lower than readState.Index' (duration: 264.513µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:45:01.472462Z","caller":"traceutil/trace.go:171","msg":"trace[991074021] transaction","detail":"{read_only:false; response_revision:3179; number_of_response:1; }","duration":"107.329071ms","start":"2026-07-17T21:45:01.365086Z","end":"2026-07-17T21:45:01.472415Z","steps":["trace[991074021] 'process raft request' (duration: 107.008257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:45:01.472812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.909878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-07-17T21:45:01.472998Z","caller":"traceutil/trace.go:171","msg":"trace[594039239] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3179; }","duration":"106.111379ms","start":"2026-07-17T21:45:01.366862Z","end":"2026-07-17T21:45:01.472973Z","steps":["trace[594039239] 'agreement among raft nodes before linearized reading' (duration: 105.621605ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:33.146587Z","caller":"traceutil/trace.go:171","msg":"trace[1671133594] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"167.230682ms","start":"2026-07-17T21:47:32.979335Z","end":"2026-07-17T21:47:33.146565Z","steps":["trace[1671133594] 'process raft request' (duration: 167.061108ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:33.192601Z","caller":"traceutil/trace.go:171","msg":"trace[1028568593] linearizableReadLoop","detail":"{readStateIndex:4754; appliedIndex:4751; }","duration":"141.912097ms","start":"2026-07-17T21:47:33.050615Z","end":"2026-07-17T21:47:33.192527Z","steps":["trace[1028568593] 'read index received' (duration: 95.844474ms)","trace[1028568593] 'applied index is now lower than readState.Index' (duration: 46.066273ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:33.192992Z","caller":"traceutil/trace.go:171","msg":"trace[1801856345] transaction","detail":"{read_only:false; response_revision:4585; number_of_response:1; }","duration":"168.801473ms","start":"2026-07-17T21:47:33.024166Z","end":"2026-07-17T21:47:33.192967Z","steps":["trace[1801856345] 'process raft request' (duration: 168.211048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:33.196799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.466281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:47:33.196888Z","caller":"traceutil/trace.go:171","msg":"trace[840761121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4585; }","duration":"146.317062ms","start":"2026-07-17T21:47:33.050549Z","end":"2026-07-17T21:47:33.196866Z","steps":["trace[840761121] 'agreement among raft nodes before linearized reading' (duration: 142.448651ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:38.40723Z","caller":"traceutil/trace.go:171","msg":"trace[1380254637] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"121.355418ms","start":"2026-07-17T21:47:38.285855Z","end":"2026-07-17T21:47:38.407211Z","steps":["trace[1380254637] 'process raft request' (duration: 121.03355ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:38.407244Z","caller":"traceutil/trace.go:171","msg":"trace[1765615074] linearizableReadLoop","detail":"{readStateIndex:4916; appliedIndex:4912; }","duration":"117.904418ms","start":"2026-07-17T21:47:38.289308Z","end":"2026-07-17T21:47:38.407212Z","steps":["trace[1765615074] 'read index received' (duration: 35.349476ms)","trace[1765615074] 'applied index is now lower than readState.Index' (duration: 82.553851ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:38.407639Z","caller":"traceutil/trace.go:171","msg":"trace[1652334037] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"121.571682ms","start":"2026-07-17T21:47:38.286056Z","end":"2026-07-17T21:47:38.407628Z","steps":["trace[1652334037] 'process raft request' (duration: 121.063249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:38.407831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.490043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-07-17T21:47:38.407869Z","caller":"traceutil/trace.go:171","msg":"trace[1509556593] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4742; }","duration":"118.564155ms","start":"2026-07-17T21:47:38.289293Z","end":"2026-07-17T21:47:38.407857Z","steps":["trace[1509556593] 'agreement among raft nodes before linearized reading' (duration: 118.422571ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:38.407987Z","caller":"traceutil/trace.go:171","msg":"trace[1596284864] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4742; }","duration":"119.086418ms","start":"2026-07-17T21:47:38.288893Z","end":"2026-07-17T21:47:38.407979Z","steps":["trace[1596284864] 'process raft request' (duration: 118.271266ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:38.683226Z","caller":"traceutil/trace.go:171","msg":"trace[1663062406] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4749; }","duration":"150.800818ms","start":"2026-07-17T21:47:38.532346Z","end":"2026-07-17T21:47:38.683147Z","steps":["trace[1663062406] 'process raft request' (duration: 142.540062ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:38.683573Z","caller":"traceutil/trace.go:171","msg":"trace[86704279] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"148.051767ms","start":"2026-07-17T21:47:38.535494Z","end":"2026-07-17T21:47:38.683545Z","steps":["trace[86704279] 'process raft request' (duration: 147.572044ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:38.683765Z","caller":"traceutil/trace.go:171","msg":"trace[961656813] linearizableReadLoop","detail":"{readStateIndex:4923; appliedIndex:4922; }","duration":"149.828204ms","start":"2026-07-17T21:47:38.533927Z","end":"2026-07-17T21:47:38.683755Z","steps":["trace[961656813] 'read index received' (duration: 2.66174ms)","trace[961656813] 'applied index is now lower than readState.Index' (duration: 147.164744ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:38.684237Z","caller":"traceutil/trace.go:171","msg":"trace[413827195] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"148.721204ms","start":"2026-07-17T21:47:38.535507Z","end":"2026-07-17T21:47:38.684228Z","steps":["trace[413827195] 'process raft request' (duration: 147.913562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:38.684284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.324156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-17T21:47:38.684685Z","caller":"traceutil/trace.go:171","msg":"trace[1730592499] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4751; }","duration":"150.632844ms","start":"2026-07-17T21:47:38.533909Z","end":"2026-07-17T21:47:38.684542Z","steps":["trace[1730592499] 'agreement among raft nodes before linearized reading' (duration: 150.199103ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:38.72425Z","caller":"traceutil/trace.go:171","msg":"trace[1029825715] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"186.301778ms","start":"2026-07-17T21:47:38.537928Z","end":"2026-07-17T21:47:38.72423Z","steps":["trace[1029825715] 'process raft request' (duration: 186.02142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:38.724442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.538811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-07-17T21:47:38.72464Z","caller":"traceutil/trace.go:171","msg":"trace[1801460752] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4752; }","duration":"166.765096ms","start":"2026-07-17T21:47:38.557852Z","end":"2026-07-17T21:47:38.724617Z","steps":["trace[1801460752] 'agreement among raft nodes before linearized reading' (duration: 166.377226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:39.97146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115764726146456,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T21:47:39.997201Z","caller":"traceutil/trace.go:171","msg":"trace[273063562] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"574.830155ms","start":"2026-07-17T21:47:39.42234Z","end":"2026-07-17T21:47:39.99717Z","steps":["trace[273063562] 'process raft request' (duration: 574.615379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:39.997373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:39.422319Z","time spent":"574.969898ms","remote":"127.0.0.1:43618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T21:47:40.196773Z","caller":"traceutil/trace.go:171","msg":"trace[867176368] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"438.803344ms","start":"2026-07-17T21:47:39.757929Z","end":"2026-07-17T21:47:40.196732Z","steps":["trace[867176368] 'process raft request' (duration: 438.698861ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:40.196722Z","caller":"traceutil/trace.go:171","msg":"trace[667331997] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"768.730851ms","start":"2026-07-17T21:47:39.427937Z","end":"2026-07-17T21:47:40.196667Z","steps":["trace[667331997] 'process raft request' (duration: 746.455195ms)","trace[667331997] 'compare' (duration: 21.839405ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:40.196845Z","caller":"traceutil/trace.go:171","msg":"trace[1117488010] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"453.248133ms","start":"2026-07-17T21:47:39.743554Z","end":"2026-07-17T21:47:40.196802Z","steps":["trace[1117488010] 'process raft request' (duration: 452.993536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:40.196963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:39.757916Z","time spent":"438.943727ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:47:40.197019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:39.427918Z","time spent":"768.983688ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:47:40.197036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:39.74352Z","time spent":"453.412237ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:47:40.197059Z","caller":"traceutil/trace.go:171","msg":"trace[1817102574] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"435.99733ms","start":"2026-07-17T21:47:39.761028Z","end":"2026-07-17T21:47:40.197025Z","steps":["trace[1817102574] 'process raft request' (duration: 435.654752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:40.197237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:39.76092Z","time spent":"436.229618ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:47:40.197495Z","caller":"traceutil/trace.go:171","msg":"trace[2000089186] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"435.499688ms","start":"2026-07-17T21:47:39.761982Z","end":"2026-07-17T21:47:40.197482Z","steps":["trace[2000089186] 'process raft request' (duration: 434.971614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:40.197573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:39.761972Z","time spent":"435.564099ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:47:40.197611Z","caller":"traceutil/trace.go:171","msg":"trace[992323985] linearizableReadLoop","detail":"{readStateIndex:4941; appliedIndex:4934; }","duration":"726.983406ms","start":"2026-07-17T21:47:39.470613Z","end":"2026-07-17T21:47:40.197596Z","steps":["trace[992323985] 'read index received' (duration: 526.375522ms)","trace[992323985] 'applied index is now lower than readState.Index' (duration: 200.606344ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:40.197677Z","caller":"traceutil/trace.go:171","msg":"trace[1951829304] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"424.994033ms","start":"2026-07-17T21:47:39.772636Z","end":"2026-07-17T21:47:40.19763Z","steps":["trace[1951829304] 'process raft request' (duration: 424.786717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:40.197795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:39.772626Z","time spent":"425.121326ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:47:40.197822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.211842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da88ba58-3ed9-4093-9130-87cfb2dbdc62\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-07-17T21:47:40.197865Z","caller":"traceutil/trace.go:171","msg":"trace[1342586803] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da88ba58-3ed9-4093-9130-87cfb2dbdc62; range_end:; response_count:1; response_revision:4767; }","duration":"727.297784ms","start":"2026-07-17T21:47:39.470553Z","end":"2026-07-17T21:47:40.19785Z","steps":["trace[1342586803] 'agreement among raft nodes before linearized reading' (duration: 727.149451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:40.19791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:39.470527Z","time spent":"727.375026ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3789,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da88ba58-3ed9-4093-9130-87cfb2dbdc62\" "} {"level":"warn","ts":"2026-07-17T21:47:40.198114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.759652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:47:40.198153Z","caller":"traceutil/trace.go:171","msg":"trace[1190579714] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:4767; }","duration":"191.843994ms","start":"2026-07-17T21:47:40.006298Z","end":"2026-07-17T21:47:40.198142Z","steps":["trace[1190579714] 'agreement among raft nodes before linearized reading' (duration: 191.780132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:40.198869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.387895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:47:40.199191Z","caller":"traceutil/trace.go:171","msg":"trace[1594349985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4767; }","duration":"147.703814ms","start":"2026-07-17T21:47:40.051351Z","end":"2026-07-17T21:47:40.199054Z","steps":["trace[1594349985] 'agreement among raft nodes before linearized reading' (duration: 147.304084ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:40.620128Z","caller":"traceutil/trace.go:171","msg":"trace[1852275334] linearizableReadLoop","detail":"{readStateIndex:4945; appliedIndex:4944; }","duration":"133.256715ms","start":"2026-07-17T21:47:40.486838Z","end":"2026-07-17T21:47:40.620095Z","steps":["trace[1852275334] 'read index received' (duration: 116.142864ms)","trace[1852275334] 'applied index is now lower than readState.Index' (duration: 17.112081ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:40.620272Z","caller":"traceutil/trace.go:171","msg":"trace[1359786129] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"157.05447ms","start":"2026-07-17T21:47:40.463164Z","end":"2026-07-17T21:47:40.620219Z","steps":["trace[1359786129] 'process raft request' (duration: 139.786625ms)","trace[1359786129] 'compare' (duration: 16.987448ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:47:40.620571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.699246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-17T21:47:40.620682Z","caller":"traceutil/trace.go:171","msg":"trace[541281694] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4771; }","duration":"133.86607ms","start":"2026-07-17T21:47:40.486792Z","end":"2026-07-17T21:47:40.620658Z","steps":["trace[541281694] 'agreement among raft nodes before linearized reading' (duration: 133.565552ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:46.422477Z","caller":"traceutil/trace.go:171","msg":"trace[2012396741] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"199.812987ms","start":"2026-07-17T21:47:46.222606Z","end":"2026-07-17T21:47:46.422419Z","steps":["trace[2012396741] 'process raft request' (duration: 197.539847ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:46.960645Z","caller":"traceutil/trace.go:171","msg":"trace[347362760] linearizableReadLoop","detail":"{readStateIndex:5007; appliedIndex:5003; }","duration":"630.089847ms","start":"2026-07-17T21:47:46.330538Z","end":"2026-07-17T21:47:46.960628Z","steps":["trace[347362760] 'read index received' (duration: 89.453591ms)","trace[347362760] 'applied index is now lower than readState.Index' (duration: 540.635176ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:46.960862Z","caller":"traceutil/trace.go:171","msg":"trace[909687485] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"737.931607ms","start":"2026-07-17T21:47:46.222918Z","end":"2026-07-17T21:47:46.96085Z","steps":["trace[909687485] 'process raft request' (duration: 720.066992ms)","trace[909687485] 'compare' (duration: 17.068684ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:47:46.960945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:46.222888Z","time spent":"737.992589ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:47:46.961075Z","caller":"traceutil/trace.go:171","msg":"trace[287073217] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"737.651329ms","start":"2026-07-17T21:47:46.223417Z","end":"2026-07-17T21:47:46.961068Z","steps":["trace[287073217] 'process raft request' (duration: 737.143975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:46.961111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:46.22341Z","time spent":"737.678669ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:47:46.961198Z","caller":"traceutil/trace.go:171","msg":"trace[124663304] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"737.507286ms","start":"2026-07-17T21:47:46.223685Z","end":"2026-07-17T21:47:46.961192Z","steps":["trace[124663304] 'process raft request' (duration: 736.913999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:46.961232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:46.223641Z","time spent":"737.571687ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:47:46.961371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.832767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T21:47:46.961394Z","caller":"traceutil/trace.go:171","msg":"trace[2118809636] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4831; }","duration":"630.892479ms","start":"2026-07-17T21:47:46.330495Z","end":"2026-07-17T21:47:46.961387Z","steps":["trace[2118809636] 'agreement among raft nodes before linearized reading' (duration: 630.806837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:46.961419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:46.330477Z","time spent":"630.935911ms","remote":"127.0.0.1:44090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":40,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-07-17T21:47:46.970637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.131529ms","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-17T21:47:46.970717Z","caller":"traceutil/trace.go:171","msg":"trace[148596694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4832; }","duration":"491.274974ms","start":"2026-07-17T21:47:46.479424Z","end":"2026-07-17T21:47:46.970699Z","steps":["trace[148596694] 'agreement among raft nodes before linearized reading' (duration: 491.044498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:46.970747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:46.479401Z","time spent":"491.341575ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T21:47:46.97115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.562937ms","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-17T21:47:46.971166Z","caller":"traceutil/trace.go:171","msg":"trace[257422856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4832; }","duration":"203.602419ms","start":"2026-07-17T21:47:46.767558Z","end":"2026-07-17T21:47:46.971161Z","steps":["trace[257422856] 'agreement among raft nodes before linearized reading' (duration: 203.554857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:46.971282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.581727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-17T21:47:46.971304Z","caller":"traceutil/trace.go:171","msg":"trace[1710905599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4832; }","duration":"271.623218ms","start":"2026-07-17T21:47:46.699672Z","end":"2026-07-17T21:47:46.971295Z","steps":["trace[1710905599] 'agreement among raft nodes before linearized reading' (duration: 271.564707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:46.971339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.772557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T21:47:46.971384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.568925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-17T21:47:46.971447Z","caller":"traceutil/trace.go:171","msg":"trace[1456019807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4832; }","duration":"295.698609ms","start":"2026-07-17T21:47:46.675734Z","end":"2026-07-17T21:47:46.971433Z","steps":["trace[1456019807] 'agreement among raft nodes before linearized reading' (duration: 295.461973ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:46.971441Z","caller":"traceutil/trace.go:171","msg":"trace[1313184974] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4832; }","duration":"267.94603ms","start":"2026-07-17T21:47:46.703474Z","end":"2026-07-17T21:47:46.97142Z","steps":["trace[1313184974] 'agreement among raft nodes before linearized reading' (duration: 267.743215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:46.971582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.785449ms","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-17T21:47:46.971605Z","caller":"traceutil/trace.go:171","msg":"trace[641592481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4832; }","duration":"204.863061ms","start":"2026-07-17T21:47:46.766736Z","end":"2026-07-17T21:47:46.971599Z","steps":["trace[641592481] 'agreement among raft nodes before linearized reading' (duration: 204.728918ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:47.097663Z","caller":"traceutil/trace.go:171","msg":"trace[700525351] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"121.903078ms","start":"2026-07-17T21:47:46.97574Z","end":"2026-07-17T21:47:47.097643Z","steps":["trace[700525351] 'process raft request' (duration: 121.745054ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:47.097907Z","caller":"traceutil/trace.go:171","msg":"trace[118021530] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"122.052112ms","start":"2026-07-17T21:47:46.975838Z","end":"2026-07-17T21:47:47.09789Z","steps":["trace[118021530] 'process raft request' (duration: 121.740334ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:47.097635Z","caller":"traceutil/trace.go:171","msg":"trace[116818692] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"121.909348ms","start":"2026-07-17T21:47:46.975679Z","end":"2026-07-17T21:47:47.097589Z","steps":["trace[116818692] 'process raft request' (duration: 106.519448ms)","trace[116818692] 'compare' (duration: 15.103253ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:47.098424Z","caller":"traceutil/trace.go:171","msg":"trace[1572377308] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"116.73189ms","start":"2026-07-17T21:47:46.981663Z","end":"2026-07-17T21:47:47.098395Z","steps":["trace[1572377308] 'process raft request' (duration: 116.101554ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:47.098522Z","caller":"traceutil/trace.go:171","msg":"trace[1637732849] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"116.69128ms","start":"2026-07-17T21:47:46.981811Z","end":"2026-07-17T21:47:47.098502Z","steps":["trace[1637732849] 'process raft request' (duration: 116.549075ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:47.098541Z","caller":"traceutil/trace.go:171","msg":"trace[719890352] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"116.852784ms","start":"2026-07-17T21:47:46.98166Z","end":"2026-07-17T21:47:47.098513Z","steps":["trace[719890352] 'process raft request' (duration: 116.565436ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:48.105679Z","caller":"traceutil/trace.go:171","msg":"trace[972271334] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"134.991006ms","start":"2026-07-17T21:47:47.970665Z","end":"2026-07-17T21:47:48.105656Z","steps":["trace[972271334] 'process raft request' (duration: 123.753515ms)","trace[972271334] 'compare' (duration: 11.144678ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:48.11248Z","caller":"traceutil/trace.go:171","msg":"trace[545014855] linearizableReadLoop","detail":"{readStateIndex:5027; appliedIndex:5025; }","duration":"108.89388ms","start":"2026-07-17T21:47:48.003564Z","end":"2026-07-17T21:47:48.112458Z","steps":["trace[545014855] 'read index received' (duration: 90.929449ms)","trace[545014855] 'applied index is now lower than readState.Index' (duration: 17.963041ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:48.112517Z","caller":"traceutil/trace.go:171","msg":"trace[1077713826] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"109.145266ms","start":"2026-07-17T21:47:48.003334Z","end":"2026-07-17T21:47:48.11248Z","steps":["trace[1077713826] 'process raft request' (duration: 109.009723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:48.112806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.211788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-17T21:47:48.112873Z","caller":"traceutil/trace.go:171","msg":"trace[1197457550] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4850; }","duration":"109.320801ms","start":"2026-07-17T21:47:48.003529Z","end":"2026-07-17T21:47:48.11285Z","steps":["trace[1197457550] 'agreement among raft nodes before linearized reading' (duration: 109.049004ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:49.218867Z","caller":"traceutil/trace.go:171","msg":"trace[263014093] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"107.143558ms","start":"2026-07-17T21:47:49.111698Z","end":"2026-07-17T21:47:49.218842Z","steps":["trace[263014093] 'process raft request' (duration: 104.125137ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:49.219333Z","caller":"traceutil/trace.go:171","msg":"trace[331426479] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"107.414264ms","start":"2026-07-17T21:47:49.111884Z","end":"2026-07-17T21:47:49.219298Z","steps":["trace[331426479] 'process raft request' (duration: 106.913641ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:49.219536Z","caller":"traceutil/trace.go:171","msg":"trace[1076803781] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"107.503696ms","start":"2026-07-17T21:47:49.112005Z","end":"2026-07-17T21:47:49.219509Z","steps":["trace[1076803781] 'process raft request' (duration: 107.126017ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:49.219689Z","caller":"traceutil/trace.go:171","msg":"trace[84668169] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"104.841986ms","start":"2026-07-17T21:47:49.114816Z","end":"2026-07-17T21:47:49.219658Z","steps":["trace[84668169] 'process raft request' (duration: 104.457135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:49.631849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.913923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:47:49.631959Z","caller":"traceutil/trace.go:171","msg":"trace[1592295255] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:4863; }","duration":"227.106848ms","start":"2026-07-17T21:47:49.404829Z","end":"2026-07-17T21:47:49.631936Z","steps":["trace[1592295255] 'count revisions from in-memory index tree' (duration: 226.714898ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:54.859595Z","caller":"traceutil/trace.go:171","msg":"trace[1631327035] linearizableReadLoop","detail":"{readStateIndex:5077; appliedIndex:5076; }","duration":"217.968248ms","start":"2026-07-17T21:47:54.64159Z","end":"2026-07-17T21:47:54.859558Z","steps":["trace[1631327035] 'read index received' (duration: 217.71513ms)","trace[1631327035] 'applied index is now lower than readState.Index' (duration: 251.998µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:54.859722Z","caller":"traceutil/trace.go:171","msg":"trace[1917106230] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"328.809631ms","start":"2026-07-17T21:47:54.530856Z","end":"2026-07-17T21:47:54.859665Z","steps":["trace[1917106230] 'process raft request' (duration: 328.476772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:54.859917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.284656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xll2s.18c3320144afc4b6\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-07-17T21:47:54.859967Z","caller":"traceutil/trace.go:171","msg":"trace[1833173725] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xll2s.18c3320144afc4b6; range_end:; response_count:1; response_revision:4899; }","duration":"218.409979ms","start":"2026-07-17T21:47:54.641541Z","end":"2026-07-17T21:47:54.859951Z","steps":["trace[1833173725] 'agreement among raft nodes before linearized reading' (duration: 218.180923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:54.859995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:47:54.53083Z","time spent":"329.043288ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:47:55.207653Z","caller":"traceutil/trace.go:171","msg":"trace[1019239894] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"147.571443ms","start":"2026-07-17T21:47:55.060051Z","end":"2026-07-17T21:47:55.207623Z","steps":["trace[1019239894] 'process raft request' (duration: 147.533662ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:55.207796Z","caller":"traceutil/trace.go:171","msg":"trace[1998107786] linearizableReadLoop","detail":"{readStateIndex:5079; appliedIndex:5078; }","duration":"155.473767ms","start":"2026-07-17T21:47:55.052288Z","end":"2026-07-17T21:47:55.207762Z","steps":["trace[1998107786] 'read index received' (duration: 104.666192ms)","trace[1998107786] 'applied index is now lower than readState.Index' (duration: 50.804995ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:55.207723Z","caller":"traceutil/trace.go:171","msg":"trace[1485754621] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"184.352838ms","start":"2026-07-17T21:47:55.0233Z","end":"2026-07-17T21:47:55.207653Z","steps":["trace[1485754621] 'process raft request' (duration: 133.622075ms)","trace[1485754621] 'compare' (duration: 50.191429ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:47:55.208164Z","caller":"traceutil/trace.go:171","msg":"trace[1456604907] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"150.352069ms","start":"2026-07-17T21:47:55.057797Z","end":"2026-07-17T21:47:55.20815Z","steps":["trace[1456604907] 'process raft request' (duration: 149.68959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:55.208043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.744384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:47:55.208262Z","caller":"traceutil/trace.go:171","msg":"trace[343582917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4903; }","duration":"156.052054ms","start":"2026-07-17T21:47:55.052196Z","end":"2026-07-17T21:47:55.208248Z","steps":["trace[343582917] 'agreement among raft nodes before linearized reading' (duration: 155.768595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:55.208335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.149887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-17T21:47:55.208437Z","caller":"traceutil/trace.go:171","msg":"trace[1887559353] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4903; }","duration":"152.339432ms","start":"2026-07-17T21:47:55.056074Z","end":"2026-07-17T21:47:55.208413Z","steps":["trace[1887559353] 'agreement among raft nodes before linearized reading' (duration: 152.014133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:55.208921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.753572ms","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-17T21:47:55.209162Z","caller":"traceutil/trace.go:171","msg":"trace[1605359577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4903; }","duration":"140.47048ms","start":"2026-07-17T21:47:55.068679Z","end":"2026-07-17T21:47:55.209149Z","steps":["trace[1605359577] 'agreement among raft nodes before linearized reading' (duration: 139.428833ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:55.481573Z","caller":"traceutil/trace.go:171","msg":"trace[688873550] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"268.306931ms","start":"2026-07-17T21:47:55.213205Z","end":"2026-07-17T21:47:55.481512Z","steps":["trace[688873550] 'process raft request' (duration: 199.699234ms)","trace[688873550] 'compare' (duration: 68.382291ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:47:55.481815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.074861ms","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-17T21:47:55.481927Z","caller":"traceutil/trace.go:171","msg":"trace[533582187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4904; }","duration":"235.231175ms","start":"2026-07-17T21:47:55.246668Z","end":"2026-07-17T21:47:55.481899Z","steps":["trace[533582187] 'agreement among raft nodes before linearized reading' (duration: 234.898606ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:55.482157Z","caller":"traceutil/trace.go:171","msg":"trace[1930095947] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5081; }","duration":"234.763823ms","start":"2026-07-17T21:47:55.246699Z","end":"2026-07-17T21:47:55.481462Z","steps":["trace[1930095947] 'read index received' (duration: 166.184438ms)","trace[1930095947] 'applied index is now lower than readState.Index' (duration: 68.577305ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:47:55.482147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.196104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T21:47:55.482253Z","caller":"traceutil/trace.go:171","msg":"trace[1695945512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4904; }","duration":"235.561063ms","start":"2026-07-17T21:47:55.246663Z","end":"2026-07-17T21:47:55.482224Z","steps":["trace[1695945512] 'agreement among raft nodes before linearized reading' (duration: 234.987308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:47:55.482381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.31212ms","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-17T21:47:55.482403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.344624ms","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-17T21:47:55.482418Z","caller":"traceutil/trace.go:171","msg":"trace[365975414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4904; }","duration":"234.381772ms","start":"2026-07-17T21:47:55.248025Z","end":"2026-07-17T21:47:55.482407Z","steps":["trace[365975414] 'agreement among raft nodes before linearized reading' (duration: 234.265759ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:47:55.482437Z","caller":"traceutil/trace.go:171","msg":"trace[1943687675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4904; }","duration":"233.431486ms","start":"2026-07-17T21:47:55.248995Z","end":"2026-07-17T21:47:55.482427Z","steps":["trace[1943687675] 'agreement among raft nodes before linearized reading' (duration: 233.335403ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:48:14.091735Z","caller":"traceutil/trace.go:171","msg":"trace[892943043] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"150.98942ms","start":"2026-07-17T21:48:13.940712Z","end":"2026-07-17T21:48:14.091702Z","steps":["trace[892943043] 'process raft request' (duration: 150.688242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:48:14.591026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.58978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:48:14.591304Z","caller":"traceutil/trace.go:171","msg":"trace[1181482523] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"313.91377ms","start":"2026-07-17T21:48:14.277369Z","end":"2026-07-17T21:48:14.591283Z","steps":["trace[1181482523] 'process raft request' (duration: 313.865069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:48:14.591376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:48:14.277333Z","time spent":"314.011973ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:48:14.591521Z","caller":"traceutil/trace.go:171","msg":"trace[540850446] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"522.12759ms","start":"2026-07-17T21:48:14.069383Z","end":"2026-07-17T21:48:14.59151Z","steps":["trace[540850446] 'process raft request' (duration: 412.952284ms)","trace[540850446] 'compare' (duration: 108.338504ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:48:14.591573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:48:14.069338Z","time spent":"522.211742ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:48:14.591654Z","caller":"traceutil/trace.go:171","msg":"trace[1823207744] linearizableReadLoop","detail":"{readStateIndex:5290; appliedIndex:5289; }","duration":"498.303635ms","start":"2026-07-17T21:48:14.093341Z","end":"2026-07-17T21:48:14.591645Z","steps":["trace[1823207744] 'read index received' (duration: 389.020685ms)","trace[1823207744] 'applied index is now lower than readState.Index' (duration: 109.281699ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:48:14.591734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.393288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:48:14.591764Z","caller":"traceutil/trace.go:171","msg":"trace[503578500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5107; }","duration":"498.47013ms","start":"2026-07-17T21:48:14.093286Z","end":"2026-07-17T21:48:14.591756Z","steps":["trace[503578500] 'agreement among raft nodes before linearized reading' (duration: 498.395568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:48:14.591793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:48:14.093261Z","time spent":"498.527002ms","remote":"127.0.0.1:43580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T21:48:14.591828Z","caller":"traceutil/trace.go:171","msg":"trace[2097199119] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"490.431957ms","start":"2026-07-17T21:48:14.101354Z","end":"2026-07-17T21:48:14.591786Z","steps":["trace[2097199119] 'process raft request' (duration: 489.807769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:48:14.592127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:48:14.101329Z","time spent":"490.597071ms","remote":"127.0.0.1:38156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3300,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T21:48:19.514025Z","caller":"traceutil/trace.go:171","msg":"trace[781408321] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"209.093387ms","start":"2026-07-17T21:48:19.304891Z","end":"2026-07-17T21:48:19.513984Z","steps":["trace[781408321] 'process raft request' (duration: 208.890621ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:48:19.542517Z","caller":"traceutil/trace.go:171","msg":"trace[1653779525] linearizableReadLoop","detail":"{readStateIndex:5316; appliedIndex:5313; }","duration":"183.996392ms","start":"2026-07-17T21:48:19.358494Z","end":"2026-07-17T21:48:19.54249Z","steps":["trace[1653779525] 'read index received' (duration: 155.332506ms)","trace[1653779525] 'applied index is now lower than readState.Index' (duration: 28.662926ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:48:19.542676Z","caller":"traceutil/trace.go:171","msg":"trace[1375088957] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"236.341343ms","start":"2026-07-17T21:48:19.306315Z","end":"2026-07-17T21:48:19.542656Z","steps":["trace[1375088957] 'process raft request' (duration: 236.130707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:48:19.542922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.378803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:48:19.543037Z","caller":"traceutil/trace.go:171","msg":"trace[1862835143] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:5130; }","duration":"184.574398ms","start":"2026-07-17T21:48:19.358438Z","end":"2026-07-17T21:48:19.543013Z","steps":["trace[1862835143] 'agreement among raft nodes before linearized reading' (duration: 184.160297ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:48:19.543103Z","caller":"traceutil/trace.go:171","msg":"trace[940261516] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"236.477577ms","start":"2026-07-17T21:48:19.306289Z","end":"2026-07-17T21:48:19.542766Z","steps":["trace[940261516] 'process raft request' (duration: 235.662674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:48:54.290469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.199732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:48:54.290725Z","caller":"traceutil/trace.go:171","msg":"trace[956963113] linearizableReadLoop","detail":"{readStateIndex:5633; appliedIndex:5632; }","duration":"268.931396ms","start":"2026-07-17T21:48:54.021775Z","end":"2026-07-17T21:48:54.290707Z","steps":["trace[956963113] 'read index received' (duration: 159.388424ms)","trace[956963113] 'applied index is now lower than readState.Index' (duration: 109.542042ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:48:54.290772Z","caller":"traceutil/trace.go:171","msg":"trace[1504527584] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"282.267218ms","start":"2026-07-17T21:48:54.008477Z","end":"2026-07-17T21:48:54.290744Z","steps":["trace[1504527584] 'process raft request' (duration: 172.676065ms)","trace[1504527584] 'compare' (duration: 108.740428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:48:54.290876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.113642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T21:48:54.290906Z","caller":"traceutil/trace.go:171","msg":"trace[507548340] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:5438; }","duration":"269.215365ms","start":"2026-07-17T21:48:54.021681Z","end":"2026-07-17T21:48:54.290896Z","steps":["trace[507548340] 'agreement among raft nodes before linearized reading' (duration: 269.119052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:48:54.291209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.275891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T21:48:54.291233Z","caller":"traceutil/trace.go:171","msg":"trace[1223023581] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:5438; }","duration":"128.448716ms","start":"2026-07-17T21:48:54.162777Z","end":"2026-07-17T21:48:54.291226Z","steps":["trace[1223023581] 'agreement among raft nodes before linearized reading' (duration: 128.322743ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:48:55.056865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2830} {"level":"info","ts":"2026-07-17T21:48:55.18168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2830,"took":"123.151106ms","hash":1297531103,"current-db-size-bytes":54218752,"current-db-size":"54 MB","current-db-size-in-use-bytes":28786688,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-17T21:48:55.18177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1297531103,"revision":2830,"compact-revision":-1} {"level":"info","ts":"2026-07-17T21:48:55.867061Z","caller":"traceutil/trace.go:171","msg":"trace[1133184227] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"107.453365ms","start":"2026-07-17T21:48:55.759559Z","end":"2026-07-17T21:48:55.867012Z","steps":["trace[1133184227] 'process raft request' (duration: 107.313801ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:48:55.867003Z","caller":"traceutil/trace.go:171","msg":"trace[1514903509] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"117.250292ms","start":"2026-07-17T21:48:55.74971Z","end":"2026-07-17T21:48:55.866961Z","steps":["trace[1514903509] 'process raft request' (duration: 116.958054ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:49:18.279922Z","caller":"traceutil/trace.go:171","msg":"trace[1622645772] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"102.315498ms","start":"2026-07-17T21:49:18.177573Z","end":"2026-07-17T21:49:18.279889Z","steps":["trace[1622645772] 'process raft request' (duration: 101.718471ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:51:45.512541Z","caller":"traceutil/trace.go:171","msg":"trace[599018959] linearizableReadLoop","detail":"{readStateIndex:6855; appliedIndex:6854; }","duration":"118.803842ms","start":"2026-07-17T21:51:45.393709Z","end":"2026-07-17T21:51:45.512513Z","steps":["trace[599018959] 'read index received' (duration: 38.84797ms)","trace[599018959] 'applied index is now lower than readState.Index' (duration: 79.954822ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:51:45.512656Z","caller":"traceutil/trace.go:171","msg":"trace[783169674] transaction","detail":"{read_only:false; response_revision:6619; number_of_response:1; }","duration":"124.554807ms","start":"2026-07-17T21:51:45.38805Z","end":"2026-07-17T21:51:45.512605Z","steps":["trace[783169674] 'process raft request' (duration: 44.576674ms)","trace[783169674] 'compare' (duration: 79.760546ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:51:45.513231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.503126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-07-17T21:51:45.513281Z","caller":"traceutil/trace.go:171","msg":"trace[450875019] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6619; }","duration":"119.601308ms","start":"2026-07-17T21:51:45.393668Z","end":"2026-07-17T21:51:45.513269Z","steps":["trace[450875019] 'agreement among raft nodes before linearized reading' (duration: 118.959787ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:51:47.06705Z","caller":"traceutil/trace.go:171","msg":"trace[675688757] transaction","detail":"{read_only:false; response_revision:6625; number_of_response:1; }","duration":"148.408126ms","start":"2026-07-17T21:51:46.918603Z","end":"2026-07-17T21:51:47.067011Z","steps":["trace[675688757] 'process raft request' (duration: 60.709044ms)","trace[675688757] 'compare' (duration: 87.401493ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:51:47.067068Z","caller":"traceutil/trace.go:171","msg":"trace[61964504] transaction","detail":"{read_only:false; response_revision:6626; number_of_response:1; }","duration":"147.354163ms","start":"2026-07-17T21:51:46.919697Z","end":"2026-07-17T21:51:47.067051Z","steps":["trace[61964504] 'process raft request' (duration: 147.201678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:51:48.374297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.541058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T21:51:48.374412Z","caller":"traceutil/trace.go:171","msg":"trace[1525855688] linearizableReadLoop","detail":"{readStateIndex:6867; appliedIndex:6866; }","duration":"129.890342ms","start":"2026-07-17T21:51:48.244506Z","end":"2026-07-17T21:51:48.374396Z","steps":["trace[1525855688] 'read index received' (duration: 15.142168ms)","trace[1525855688] 'applied index is now lower than readState.Index' (duration: 114.746914ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T21:51:48.374449Z","caller":"traceutil/trace.go:171","msg":"trace[1599638781] transaction","detail":"{read_only:false; response_revision:6630; number_of_response:1; }","duration":"353.17045ms","start":"2026-07-17T21:51:48.021253Z","end":"2026-07-17T21:51:48.374423Z","steps":["trace[1599638781] 'process raft request' (duration: 238.422576ms)","trace[1599638781] 'compare' (duration: 114.412043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T21:51:48.374567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.059837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T21:51:48.374835Z","caller":"traceutil/trace.go:171","msg":"trace[475520196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6630; }","duration":"130.354836ms","start":"2026-07-17T21:51:48.244454Z","end":"2026-07-17T21:51:48.374808Z","steps":["trace[475520196] 'agreement among raft nodes before linearized reading' (duration: 130.044716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T21:51:48.374747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T21:51:48.02122Z","time spent":"353.411847ms","remote":"127.0.0.1:43596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T21:52:09.352457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.187691ms","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-17T21:52:09.352539Z","caller":"traceutil/trace.go:171","msg":"trace[117416492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6798; }","duration":"107.325732ms","start":"2026-07-17T21:52:09.245197Z","end":"2026-07-17T21:52:09.352522Z","steps":["trace[117416492] 'range keys from in-memory index tree' (duration: 106.986329ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T21:53:55.062252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5439} {"level":"info","ts":"2026-07-17T21:53:55.18056Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5439,"took":"116.7919ms","hash":1420913529,"current-db-size-bytes":54218752,"current-db-size":"54 MB","current-db-size-in-use-bytes":18526208,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-17T21:53:55.18061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1420913529,"revision":5439,"compact-revision":2830}