{"level":"warn","ts":"2026-05-11T00:51:59.451841Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:51:59.451986Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T00:51:59.452233Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:51:59.452264Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-11T00:51:59.452299Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T00:51:59.453816Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-05-11T00:51:59.454077Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T00:51:59.460239Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.586517ms"} {"level":"info","ts":"2026-05-11T00:51:59.479568Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-11T00:51:59.479684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T00:51:59.479744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-11T00:51:59.479779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T00:51:59.479794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-11T00:51:59.479865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-11T00:51:59.748213Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T00:51:59.799893Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T00:52:00.021568Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T00:52:00.35412Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T00:52:00.354946Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T00:52:00.354831Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T00:52:00.355213Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T00:52:00.355243Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T00:52:00.357455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-11T00:52:00.357678Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-11T00:52:00.360969Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T00:52:00.361064Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-11T00:52:00.361124Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-11T00:52:00.361458Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T00:52:00.361528Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T00:52:00.780328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T00:52:00.780429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T00:52:00.78046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-11T00:52:00.780489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-11T00:52:00.780508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-11T00:52:00.780533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-11T00:52:00.780555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-11T00:52:00.782005Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:52:00.782236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:52:00.782241Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T00:52:00.78228Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:52:00.782725Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T00:52:00.782803Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T00:52:00.784318Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:52:00.784501Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:52:00.784555Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:52:00.788422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-05-11T00:52:00.788934Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T00:52:03.288213Z","caller":"traceutil/trace.go:171","msg":"trace[734052718] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:120; }","duration":"102.54003ms","start":"2026-05-11T00:52:03.185635Z","end":"2026-05-11T00:52:03.288175Z","steps":["trace[734052718] 'read index received' (duration: 29.421222ms)","trace[734052718] 'applied index is now lower than readState.Index' (duration: 73.117088ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:03.288357Z","caller":"traceutil/trace.go:171","msg":"trace[916641785] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"140.09055ms","start":"2026-05-11T00:52:03.148228Z","end":"2026-05-11T00:52:03.288319Z","steps":["trace[916641785] 'process raft request' (duration: 66.954862ms)","trace[916641785] 'compare' (duration: 72.782037ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:03.288477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.792245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T00:52:03.288626Z","caller":"traceutil/trace.go:171","msg":"trace[1658004218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:116; }","duration":"102.999277ms","start":"2026-05-11T00:52:03.185609Z","end":"2026-05-11T00:52:03.288609Z","steps":["trace[1658004218] 'agreement among raft nodes before linearized reading' (duration: 102.745722ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:04.710823Z","caller":"traceutil/trace.go:171","msg":"trace[1098603030] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"112.97087ms","start":"2026-05-11T00:52:04.597824Z","end":"2026-05-11T00:52:04.710795Z","steps":["trace[1098603030] 'process raft request' (duration: 57.2565ms)","trace[1098603030] 'compare' (duration: 55.587093ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:29.514537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.148133ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T00:52:29.517187Z","caller":"traceutil/trace.go:171","msg":"trace[541652528] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"109.228099ms","start":"2026-05-11T00:52:29.407938Z","end":"2026-05-11T00:52:29.517167Z","steps":["trace[541652528] 'process raft request' (duration: 109.120721ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:29.524397Z","caller":"traceutil/trace.go:171","msg":"trace[461862933] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"111.326275ms","start":"2026-05-11T00:52:29.413041Z","end":"2026-05-11T00:52:29.524368Z","steps":["trace[461862933] 'process raft request' (duration: 110.385815ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:29.524557Z","caller":"traceutil/trace.go:171","msg":"trace[1802240028] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"108.522846ms","start":"2026-05-11T00:52:29.416023Z","end":"2026-05-11T00:52:29.524546Z","steps":["trace[1802240028] 'process raft request' (duration: 107.693274ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:29.524747Z","caller":"traceutil/trace.go:171","msg":"trace[2061397856] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"105.78277ms","start":"2026-05-11T00:52:29.418954Z","end":"2026-05-11T00:52:29.524737Z","steps":["trace[2061397856] 'process raft request' (duration: 104.797947ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:29.524935Z","caller":"traceutil/trace.go:171","msg":"trace[1076569192] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"105.334157ms","start":"2026-05-11T00:52:29.41959Z","end":"2026-05-11T00:52:29.524925Z","steps":["trace[1076569192] 'process raft request' (duration: 104.197662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:30.596475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.735777ms","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-05-11T00:52:30.596698Z","caller":"traceutil/trace.go:171","msg":"trace[2105604444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"103.000297ms","start":"2026-05-11T00:52:30.493674Z","end":"2026-05-11T00:52:30.596674Z","steps":["trace[2105604444] 'range keys from in-memory index tree' (duration: 102.628978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:30.833519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.764415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-11T00:52:30.833591Z","caller":"traceutil/trace.go:171","msg":"trace[1808180649] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:579; }","duration":"107.875944ms","start":"2026-05-11T00:52:30.725699Z","end":"2026-05-11T00:52:30.833575Z","steps":["trace[1808180649] 'range keys from in-memory index tree' (duration: 107.586812ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:30.843216Z","caller":"traceutil/trace.go:171","msg":"trace[1926960283] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"100.759557ms","start":"2026-05-11T00:52:30.742433Z","end":"2026-05-11T00:52:30.843192Z","steps":["trace[1926960283] 'process raft request' (duration: 100.5363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:31.060472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.185537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:29476"} {"level":"info","ts":"2026-05-11T00:52:31.060568Z","caller":"traceutil/trace.go:171","msg":"trace[1136691823] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:582; }","duration":"115.326008ms","start":"2026-05-11T00:52:30.945219Z","end":"2026-05-11T00:52:31.060545Z","steps":["trace[1136691823] 'range keys from in-memory index tree' (duration: 114.971472ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:31.085352Z","caller":"traceutil/trace.go:171","msg":"trace[591535842] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"135.717006ms","start":"2026-05-11T00:52:30.949595Z","end":"2026-05-11T00:52:31.085312Z","steps":["trace[591535842] 'process raft request' (duration: 135.417464ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:34.986485Z","caller":"traceutil/trace.go:171","msg":"trace[909975360] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"348.695948ms","start":"2026-05-11T00:52:34.637746Z","end":"2026-05-11T00:52:34.986441Z","steps":["trace[909975360] 'process raft request' (duration: 348.415567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:34.987363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:34.637726Z","time spent":"348.875032ms","remote":"127.0.0.1:39448","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-05-11T00:52:35.011604Z","caller":"traceutil/trace.go:171","msg":"trace[1130911180] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"133.095898ms","start":"2026-05-11T00:52:34.878475Z","end":"2026-05-11T00:52:35.01157Z","steps":["trace[1130911180] 'read index received' (duration: 107.69843ms)","trace[1130911180] 'applied index is now lower than readState.Index' (duration: 25.395828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:35.01187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.37831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T00:52:35.011994Z","caller":"traceutil/trace.go:171","msg":"trace[1651640164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"133.564374ms","start":"2026-05-11T00:52:34.878409Z","end":"2026-05-11T00:52:35.011973Z","steps":["trace[1651640164] 'agreement among raft nodes before linearized reading' (duration: 133.25294ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:35.012431Z","caller":"traceutil/trace.go:171","msg":"trace[1688413310] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"255.305684ms","start":"2026-05-11T00:52:34.756311Z","end":"2026-05-11T00:52:35.011617Z","steps":["trace[1688413310] 'process raft request' (duration: 253.516995ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:36.021941Z","caller":"traceutil/trace.go:171","msg":"trace[1804001170] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"102.460531ms","start":"2026-05-11T00:52:35.919443Z","end":"2026-05-11T00:52:36.021904Z","steps":["trace[1804001170] 'process raft request' (duration: 102.19394ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:36.437962Z","caller":"traceutil/trace.go:171","msg":"trace[649910065] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"459.867996ms","start":"2026-05-11T00:52:35.978062Z","end":"2026-05-11T00:52:36.437931Z","steps":["trace[649910065] 'process raft request' (duration: 400.748193ms)","trace[649910065] 'compare' (duration: 56.190395ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:36.438116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:35.978029Z","time spent":"460.003236ms","remote":"127.0.0.1:39286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:52:36.771238Z","caller":"traceutil/trace.go:171","msg":"trace[23340792] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"637.650855ms","start":"2026-05-11T00:52:36.133506Z","end":"2026-05-11T00:52:36.771157Z","steps":["trace[23340792] 'read index received' (duration: 244.44979ms)","trace[23340792] 'applied index is now lower than readState.Index' (duration: 393.199805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:36.771491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.958248ms","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-05-11T00:52:36.771542Z","caller":"traceutil/trace.go:171","msg":"trace[1299538544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"638.060176ms","start":"2026-05-11T00:52:36.133458Z","end":"2026-05-11T00:52:36.771518Z","steps":["trace[1299538544] 'agreement among raft nodes before linearized reading' (duration: 637.873241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:36.771594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:36.133441Z","time spent":"638.144123ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T00:52:36.771778Z","caller":"traceutil/trace.go:171","msg":"trace[1462704107] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"741.457238ms","start":"2026-05-11T00:52:36.030304Z","end":"2026-05-11T00:52:36.771762Z","steps":["trace[1462704107] 'process raft request' (duration: 740.694829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:36.771982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:36.030282Z","time spent":"741.60156ms","remote":"127.0.0.1:39448","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-05-11T00:52:37.189441Z","caller":"traceutil/trace.go:171","msg":"trace[1255276417] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"619.501797ms","start":"2026-05-11T00:52:36.569907Z","end":"2026-05-11T00:52:37.189409Z","steps":["trace[1255276417] 'process raft request' (duration: 619.445913ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:37.189655Z","caller":"traceutil/trace.go:171","msg":"trace[1336242442] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"686.441198ms","start":"2026-05-11T00:52:36.503179Z","end":"2026-05-11T00:52:37.18962Z","steps":["trace[1336242442] 'process raft request' (duration: 626.60681ms)","trace[1336242442] 'compare' (duration: 59.243482ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:37.189802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:36.503148Z","time spent":"686.589289ms","remote":"127.0.0.1:39698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:52:37.189924Z","caller":"traceutil/trace.go:171","msg":"trace[960450810] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"633.700551ms","start":"2026-05-11T00:52:36.556172Z","end":"2026-05-11T00:52:37.189872Z","steps":["trace[960450810] 'process raft request' (duration: 633.112305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:37.190174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:36.556144Z","time spent":"633.899176ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:52:37.189638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:36.569896Z","time spent":"619.6632ms","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:52:37.273223Z","caller":"traceutil/trace.go:171","msg":"trace[335556367] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:635; }","duration":"281.215847ms","start":"2026-05-11T00:52:36.99198Z","end":"2026-05-11T00:52:37.273196Z","steps":["trace[335556367] 'read index received' (duration: 137.962981ms)","trace[335556367] 'applied index is now lower than readState.Index' (duration: 143.251406ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:52:37.273402Z","caller":"traceutil/trace.go:171","msg":"trace[2084729122] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"492.385752ms","start":"2026-05-11T00:52:36.78088Z","end":"2026-05-11T00:52:37.273265Z","steps":["trace[2084729122] 'process raft request' (duration: 492.179736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:37.27366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.521829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T00:52:37.273708Z","caller":"traceutil/trace.go:171","msg":"trace[131586174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"248.587124ms","start":"2026-05-11T00:52:37.025107Z","end":"2026-05-11T00:52:37.273694Z","steps":["trace[131586174] 'agreement among raft nodes before linearized reading' (duration: 248.483466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:37.273682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:36.780853Z","time spent":"492.716267ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:52:37.273458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.476437ms","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-05-11T00:52:37.273425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.905192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:52:37.27387Z","caller":"traceutil/trace.go:171","msg":"trace[2043056190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"281.945955ms","start":"2026-05-11T00:52:36.991915Z","end":"2026-05-11T00:52:37.27386Z","steps":["trace[2043056190] 'agreement among raft nodes before linearized reading' (duration: 281.397871ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:37.273915Z","caller":"traceutil/trace.go:171","msg":"trace[1491718547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"268.462736ms","start":"2026-05-11T00:52:37.005434Z","end":"2026-05-11T00:52:37.273897Z","steps":["trace[1491718547] 'agreement among raft nodes before linearized reading' (duration: 267.935604ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:37.798538Z","caller":"traceutil/trace.go:171","msg":"trace[904869050] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"518.257246ms","start":"2026-05-11T00:52:37.280244Z","end":"2026-05-11T00:52:37.798501Z","steps":["trace[904869050] 'process raft request' (duration: 503.553329ms)","trace[904869050] 'compare' (duration: 14.257442ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:52:37.798724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:37.280221Z","time spent":"518.402756ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:52:37.810547Z","caller":"traceutil/trace.go:171","msg":"trace[1009972401] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"529.866572ms","start":"2026-05-11T00:52:37.28064Z","end":"2026-05-11T00:52:37.810506Z","steps":["trace[1009972401] 'process raft request' (duration: 529.315688ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:37.810694Z","caller":"traceutil/trace.go:171","msg":"trace[674842126] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"133.150159ms","start":"2026-05-11T00:52:37.67752Z","end":"2026-05-11T00:52:37.81067Z","steps":["trace[674842126] 'process raft request' (duration: 132.77912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:37.810735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:37.280628Z","time spent":"530.005603ms","remote":"127.0.0.1:39448","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-05-11T00:52:39.422182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.395201ms","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-05-11T00:52:39.422262Z","caller":"traceutil/trace.go:171","msg":"trace[1078328952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"143.522372ms","start":"2026-05-11T00:52:39.278718Z","end":"2026-05-11T00:52:39.42224Z","steps":["trace[1078328952] 'range keys from in-memory index tree' (duration: 143.256871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:53.354865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.39853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:52:53.354977Z","caller":"traceutil/trace.go:171","msg":"trace[838901518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"346.553653ms","start":"2026-05-11T00:52:53.008404Z","end":"2026-05-11T00:52:53.354958Z","steps":["trace[838901518] 'range keys from in-memory index tree' (duration: 346.310854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:53.354955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.562065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xjfvj\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-05-11T00:52:53.355024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:53.008383Z","time spent":"346.630249ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T00:52:53.355703Z","caller":"traceutil/trace.go:171","msg":"trace[476194477] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xjfvj; range_end:; response_count:1; response_revision:775; }","duration":"342.707197ms","start":"2026-05-11T00:52:53.012333Z","end":"2026-05-11T00:52:53.35504Z","steps":["trace[476194477] 'range keys from in-memory index tree' (duration: 342.323256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:52:53.355777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:52:53.01232Z","time spent":"343.439008ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xjfvj\" "} {"level":"info","ts":"2026-05-11T00:53:21.212362Z","caller":"traceutil/trace.go:171","msg":"trace[1382274581] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"136.034523ms","start":"2026-05-11T00:53:21.076299Z","end":"2026-05-11T00:53:21.212333Z","steps":["trace[1382274581] 'process raft request' (duration: 135.870134ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:09.613287Z","caller":"traceutil/trace.go:171","msg":"trace[1019552863] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"205.240952ms","start":"2026-05-11T00:54:09.408024Z","end":"2026-05-11T00:54:09.613265Z","steps":["trace[1019552863] 'process raft request' (duration: 205.091131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:10.025688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.642396ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T00:54:10.02599Z","caller":"traceutil/trace.go:171","msg":"trace[1884811021] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"608.312915ms","start":"2026-05-11T00:54:09.417659Z","end":"2026-05-11T00:54:10.025972Z","steps":["trace[1884811021] 'process raft request' (duration: 608.124064ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:10.026059Z","caller":"traceutil/trace.go:171","msg":"trace[705161742] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1913; }","duration":"572.608123ms","start":"2026-05-11T00:54:09.45339Z","end":"2026-05-11T00:54:10.025998Z","steps":["trace[705161742] 'read index received' (duration: 159.734713ms)","trace[705161742] 'applied index is now lower than readState.Index' (duration: 412.86762ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:54:10.02625Z","caller":"traceutil/trace.go:171","msg":"trace[239185250] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"379.926816ms","start":"2026-05-11T00:54:09.646294Z","end":"2026-05-11T00:54:10.026221Z","steps":["trace[239185250] 'process raft request' (duration: 379.605474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:10.026422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:09.646274Z","time spent":"380.060167ms","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7741,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:54:10.026432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.026256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-11T00:54:10.026469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.726283ms","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-05-11T00:54:10.026143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:09.417626Z","time spent":"608.405106ms","remote":"127.0.0.1:39448","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-05-11T00:54:10.026516Z","caller":"traceutil/trace.go:171","msg":"trace[1780734648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1847; }","duration":"573.162496ms","start":"2026-05-11T00:54:09.453342Z","end":"2026-05-11T00:54:10.026505Z","steps":["trace[1780734648] 'agreement among raft nodes before linearized reading' (duration: 572.904804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:10.026606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:09.453321Z","time spent":"573.275277ms","remote":"127.0.0.1:39448","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-05-11T00:54:10.026625Z","caller":"traceutil/trace.go:171","msg":"trace[1546880190] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1847; }","duration":"473.890804ms","start":"2026-05-11T00:54:09.552674Z","end":"2026-05-11T00:54:10.026564Z","steps":["trace[1546880190] 'agreement among raft nodes before linearized reading' (duration: 473.646872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:10.026728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:09.552655Z","time spent":"474.059245ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-11T00:54:22.429353Z","caller":"traceutil/trace.go:171","msg":"trace[1863930686] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"256.943323ms","start":"2026-05-11T00:54:22.172385Z","end":"2026-05-11T00:54:22.429329Z","steps":["trace[1863930686] 'process raft request' (duration: 247.027579ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:26.061669Z","caller":"traceutil/trace.go:171","msg":"trace[2055557545] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"206.79117ms","start":"2026-05-11T00:54:25.854849Z","end":"2026-05-11T00:54:26.06164Z","steps":["trace[2055557545] 'process raft request' (duration: 206.580936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:49.292343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.280346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:54:49.292465Z","caller":"traceutil/trace.go:171","msg":"trace[2062312222] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2112; }","duration":"100.444763ms","start":"2026-05-11T00:54:49.191985Z","end":"2026-05-11T00:54:49.29243Z","steps":["trace[2062312222] 'count revisions from in-memory index tree' (duration: 100.216064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:49.292801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.032169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-11T00:54:49.292963Z","caller":"traceutil/trace.go:171","msg":"trace[961214655] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2112; }","duration":"269.143242ms","start":"2026-05-11T00:54:49.023733Z","end":"2026-05-11T00:54:49.292876Z","steps":["trace[961214655] 'range keys from in-memory index tree' (duration: 268.731977ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:50.575005Z","caller":"traceutil/trace.go:171","msg":"trace[1959811271] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2195; }","duration":"202.179018ms","start":"2026-05-11T00:54:50.372799Z","end":"2026-05-11T00:54:50.574978Z","steps":["trace[1959811271] 'read index received' (duration: 200.797897ms)","trace[1959811271] 'applied index is now lower than readState.Index' (duration: 1.379541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:54:50.575225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.396147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-11T00:54:50.575298Z","caller":"traceutil/trace.go:171","msg":"trace[562729099] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2117; }","duration":"202.50943ms","start":"2026-05-11T00:54:50.372773Z","end":"2026-05-11T00:54:50.575283Z","steps":["trace[562729099] 'agreement among raft nodes before linearized reading' (duration: 202.305803ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:50.575599Z","caller":"traceutil/trace.go:171","msg":"trace[2006226375] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"225.651796ms","start":"2026-05-11T00:54:50.349903Z","end":"2026-05-11T00:54:50.575555Z","steps":["trace[2006226375] 'process raft request' (duration: 223.775737ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:55:39.811937Z","caller":"traceutil/trace.go:171","msg":"trace[679118142] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"251.520846ms","start":"2026-05-11T00:55:39.560393Z","end":"2026-05-11T00:55:39.811914Z","steps":["trace[679118142] 'process raft request' (duration: 251.347107ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:56:07.141613Z","caller":"traceutil/trace.go:171","msg":"trace[511713394] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"106.799578ms","start":"2026-05-11T00:56:07.034791Z","end":"2026-05-11T00:56:07.141591Z","steps":["trace[511713394] 'process raft request' (duration: 12.709357ms)","trace[511713394] 'compare' (duration: 94.003586ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:56:12.706215Z","caller":"traceutil/trace.go:171","msg":"trace[1123305668] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"159.372592ms","start":"2026-05-11T00:56:12.546803Z","end":"2026-05-11T00:56:12.706176Z","steps":["trace[1123305668] 'process raft request' (duration: 159.114726ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:56:12.731864Z","caller":"traceutil/trace.go:171","msg":"trace[1575759733] transaction","detail":"{read_only:false; response_revision:2670; number_of_response:1; }","duration":"129.093761ms","start":"2026-05-11T00:56:12.602741Z","end":"2026-05-11T00:56:12.731835Z","steps":["trace[1575759733] 'process raft request' (duration: 128.895559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:56:13.117636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.408806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:56:13.117751Z","caller":"traceutil/trace.go:171","msg":"trace[592130382] linearizableReadLoop","detail":"{readStateIndex:2769; appliedIndex:2768; }","duration":"135.481468ms","start":"2026-05-11T00:56:12.982252Z","end":"2026-05-11T00:56:13.117734Z","steps":["trace[592130382] 'read index received' (duration: 35.132µs)","trace[592130382] 'applied index is now lower than readState.Index' (duration: 135.444656ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:56:13.118034Z","caller":"traceutil/trace.go:171","msg":"trace[668290207] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"342.584227ms","start":"2026-05-11T00:56:12.775429Z","end":"2026-05-11T00:56:13.118013Z","steps":["trace[668290207] 'process raft request' (duration: 114.650279ms)","trace[668290207] 'compare' (duration: 227.262497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:56:13.118111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:56:12.775413Z","time spent":"342.663852ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:56:13.118022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.749284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T00:56:13.118171Z","caller":"traceutil/trace.go:171","msg":"trace[1059667607] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2671; }","duration":"135.951547ms","start":"2026-05-11T00:56:12.982205Z","end":"2026-05-11T00:56:13.118156Z","steps":["trace[1059667607] 'agreement among raft nodes before linearized reading' (duration: 135.746194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:56:13.123914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.883771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:56:13.124335Z","caller":"traceutil/trace.go:171","msg":"trace[1690665976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2672; }","duration":"117.340289ms","start":"2026-05-11T00:56:13.006958Z","end":"2026-05-11T00:56:13.124298Z","steps":["trace[1690665976] 'agreement among raft nodes before linearized reading' (duration: 116.751953ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:11.773913Z","caller":"traceutil/trace.go:171","msg":"trace[2025359673] transaction","detail":"{read_only:false; response_revision:3298; number_of_response:1; }","duration":"118.13087ms","start":"2026-05-11T00:58:11.655748Z","end":"2026-05-11T00:58:11.773879Z","steps":["trace[2025359673] 'process raft request' (duration: 97.056069ms)","trace[2025359673] 'compare' (duration: 20.736099ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:58:27.417256Z","caller":"traceutil/trace.go:171","msg":"trace[1351892190] transaction","detail":"{read_only:false; response_revision:3463; number_of_response:1; }","duration":"121.66355ms","start":"2026-05-11T00:58:27.295563Z","end":"2026-05-11T00:58:27.417226Z","steps":["trace[1351892190] 'process raft request' (duration: 121.459582ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:19.609434Z","caller":"traceutil/trace.go:171","msg":"trace[1947566908] transaction","detail":"{read_only:false; response_revision:3791; number_of_response:1; }","duration":"117.32501ms","start":"2026-05-11T00:59:19.492026Z","end":"2026-05-11T00:59:19.609351Z","steps":["trace[1947566908] 'process raft request' (duration: 73.487312ms)","trace[1947566908] 'compare' (duration: 43.240352ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:06.847552Z","caller":"traceutil/trace.go:171","msg":"trace[1331453390] transaction","detail":"{read_only:false; response_revision:4391; number_of_response:1; }","duration":"110.771789ms","start":"2026-05-11T01:00:06.736737Z","end":"2026-05-11T01:00:06.847509Z","steps":["trace[1331453390] 'process raft request' (duration: 95.375608ms)","trace[1331453390] 'compare' (duration: 15.027482ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:26.783532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.778001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T01:00:26.783759Z","caller":"traceutil/trace.go:171","msg":"trace[1517655965] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"173.388839ms","start":"2026-05-11T01:00:26.610351Z","end":"2026-05-11T01:00:26.78374Z","steps":["trace[1517655965] 'process raft request' (duration: 25.31704ms)","trace[1517655965] 'compare' (duration: 147.167453ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:26.784236Z","caller":"traceutil/trace.go:171","msg":"trace[1137449891] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"157.662715ms","start":"2026-05-11T01:00:26.626548Z","end":"2026-05-11T01:00:26.784211Z","steps":["trace[1137449891] 'process raft request' (duration: 157.089188ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:27.01866Z","caller":"traceutil/trace.go:171","msg":"trace[2140382119] linearizableReadLoop","detail":"{readStateIndex:4933; appliedIndex:4931; }","duration":"228.454979ms","start":"2026-05-11T01:00:26.790182Z","end":"2026-05-11T01:00:27.018637Z","steps":["trace[2140382119] 'read index received' (duration: 16.240937ms)","trace[2140382119] 'applied index is now lower than readState.Index' (duration: 212.212772ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:27.018799Z","caller":"traceutil/trace.go:171","msg":"trace[1797512051] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"228.871973ms","start":"2026-05-11T01:00:26.789913Z","end":"2026-05-11T01:00:27.018785Z","steps":["trace[1797512051] 'process raft request' (duration: 193.284283ms)","trace[1797512051] 'compare' (duration: 35.033293ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:27.019006Z","caller":"traceutil/trace.go:171","msg":"trace[1973386088] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"225.437193ms","start":"2026-05-11T01:00:26.793553Z","end":"2026-05-11T01:00:27.01899Z","steps":["trace[1973386088] 'process raft request' (duration: 224.948049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:27.019348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.876103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-05-11T01:00:27.01939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.186751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T01:00:27.019447Z","caller":"traceutil/trace.go:171","msg":"trace[849439091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4755; }","duration":"229.285404ms","start":"2026-05-11T01:00:26.790149Z","end":"2026-05-11T01:00:27.019435Z","steps":["trace[849439091] 'agreement among raft nodes before linearized reading' (duration: 229.14417ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:27.019445Z","caller":"traceutil/trace.go:171","msg":"trace[580298796] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4755; }","duration":"228.023148ms","start":"2026-05-11T01:00:26.791407Z","end":"2026-05-11T01:00:27.01943Z","steps":["trace[580298796] 'agreement among raft nodes before linearized reading' (duration: 227.696708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:27.019559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.930786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-05-11T01:00:27.019565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.342369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-11T01:00:27.019661Z","caller":"traceutil/trace.go:171","msg":"trace[1687589493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4755; }","duration":"226.467722ms","start":"2026-05-11T01:00:26.793174Z","end":"2026-05-11T01:00:27.019641Z","steps":["trace[1687589493] 'agreement among raft nodes before linearized reading' (duration: 226.222735ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:27.019601Z","caller":"traceutil/trace.go:171","msg":"trace[969110588] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4755; }","duration":"226.988177ms","start":"2026-05-11T01:00:26.792601Z","end":"2026-05-11T01:00:27.01959Z","steps":["trace[969110588] 'agreement among raft nodes before linearized reading' (duration: 226.869364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:27.019779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.541921ms","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-05-11T01:00:27.019886Z","caller":"traceutil/trace.go:171","msg":"trace[1974194927] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4755; }","duration":"204.714446ms","start":"2026-05-11T01:00:26.815149Z","end":"2026-05-11T01:00:27.019864Z","steps":["trace[1974194927] 'agreement among raft nodes before linearized reading' (duration: 204.403306ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:27.018906Z","caller":"traceutil/trace.go:171","msg":"trace[1356421861] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"219.991236ms","start":"2026-05-11T01:00:26.798872Z","end":"2026-05-11T01:00:27.018863Z","steps":["trace[1356421861] 'process raft request' (duration: 219.696837ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:33.504673Z","caller":"traceutil/trace.go:171","msg":"trace[1463543133] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"378.623001ms","start":"2026-05-11T01:00:33.126016Z","end":"2026-05-11T01:00:33.504639Z","steps":["trace[1463543133] 'process raft request' (duration: 362.183072ms)","trace[1463543133] 'compare' (duration: 15.594983ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:33.504843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:33.125997Z","time spent":"378.759574ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:00:33.505038Z","caller":"traceutil/trace.go:171","msg":"trace[152618772] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"375.910659ms","start":"2026-05-11T01:00:33.129056Z","end":"2026-05-11T01:00:33.504967Z","steps":["trace[152618772] 'process raft request' (duration: 375.487906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:33.50576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:33.129041Z","time spent":"376.113804ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:00:39.309607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.893172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T01:00:39.309734Z","caller":"traceutil/trace.go:171","msg":"trace[352311358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4875; }","duration":"218.061478ms","start":"2026-05-11T01:00:39.091628Z","end":"2026-05-11T01:00:39.309689Z","steps":["trace[352311358] 'range keys from in-memory index tree' (duration: 217.699356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:39.310499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.361472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T01:00:39.310728Z","caller":"traceutil/trace.go:171","msg":"trace[55147461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4875; }","duration":"215.635131ms","start":"2026-05-11T01:00:39.09506Z","end":"2026-05-11T01:00:39.310696Z","steps":["trace[55147461] 'range keys from in-memory index tree' (duration: 215.058752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:40.23121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.050309ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T01:00:40.231484Z","caller":"traceutil/trace.go:171","msg":"trace[1581476106] linearizableReadLoop","detail":"{readStateIndex:5062; appliedIndex:5061; }","duration":"830.652942ms","start":"2026-05-11T01:00:39.400811Z","end":"2026-05-11T01:00:40.231464Z","steps":["trace[1581476106] 'read index received' (duration: 308.279713ms)","trace[1581476106] 'applied index is now lower than readState.Index' (duration: 522.371829ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:40.231692Z","caller":"traceutil/trace.go:171","msg":"trace[590830641] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"167.117884ms","start":"2026-05-11T01:00:40.064543Z","end":"2026-05-11T01:00:40.231661Z","steps":["trace[590830641] 'process raft request' (duration: 166.812864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:40.304301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.362919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T01:00:40.304361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.297908ms","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-05-11T01:00:40.304415Z","caller":"traceutil/trace.go:171","msg":"trace[2128969637] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4878; }","duration":"903.632967ms","start":"2026-05-11T01:00:39.400751Z","end":"2026-05-11T01:00:40.304384Z","steps":["trace[2128969637] 'agreement among raft nodes before linearized reading' (duration: 831.004473ms)","trace[2128969637] 'count revisions from in-memory index tree' (duration: 72.328254ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:40.304433Z","caller":"traceutil/trace.go:171","msg":"trace[1208813571] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4878; }","duration":"637.449424ms","start":"2026-05-11T01:00:39.666961Z","end":"2026-05-11T01:00:40.30441Z","steps":["trace[1208813571] 'agreement among raft nodes before linearized reading' (duration: 564.877951ms)","trace[1208813571] 'range keys from in-memory index tree' (duration: 72.375756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:40.304478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:39.666931Z","time spent":"637.537827ms","remote":"127.0.0.1:39352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-11T01:00:40.304492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:39.40073Z","time spent":"903.739932ms","remote":"127.0.0.1:34662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:00:40.304728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.310515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:00:40.304805Z","caller":"traceutil/trace.go:171","msg":"trace[1949295804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4878; }","duration":"297.425419ms","start":"2026-05-11T01:00:40.007366Z","end":"2026-05-11T01:00:40.304792Z","steps":["trace[1949295804] 'agreement among raft nodes before linearized reading' (duration: 224.488144ms)","trace[1949295804] 'range keys from in-memory index tree' (duration: 72.833711ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:40.304932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.163787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-05-11T01:00:40.305028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.522126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:00:40.305041Z","caller":"traceutil/trace.go:171","msg":"trace[133987053] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4878; }","duration":"710.350293ms","start":"2026-05-11T01:00:39.594663Z","end":"2026-05-11T01:00:40.305013Z","steps":["trace[133987053] 'agreement among raft nodes before linearized reading' (duration: 637.214702ms)","trace[133987053] 'range keys from in-memory index tree' (duration: 72.815861ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:40.305068Z","caller":"traceutil/trace.go:171","msg":"trace[603335198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4878; }","duration":"826.65028ms","start":"2026-05-11T01:00:39.478406Z","end":"2026-05-11T01:00:40.305057Z","steps":["trace[603335198] 'agreement among raft nodes before linearized reading' (duration: 753.435597ms)","trace[603335198] 'range keys from in-memory index tree' (duration: 73.13933ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:40.3051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:39.478381Z","time spent":"826.712852ms","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T01:00:40.305147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.658914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:00:40.305177Z","caller":"traceutil/trace.go:171","msg":"trace[1657430920] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4878; }","duration":"386.736076ms","start":"2026-05-11T01:00:39.918427Z","end":"2026-05-11T01:00:40.305163Z","steps":["trace[1657430920] 'agreement among raft nodes before linearized reading' (duration: 313.4371ms)","trace[1657430920] 'count revisions from in-memory index tree' (duration: 73.253734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:40.305206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:39.918402Z","time spent":"386.798408ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:00:40.305097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:39.594648Z","time spent":"710.439255ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-11T01:00:40.305477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.126801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-11T01:00:40.305536Z","caller":"traceutil/trace.go:171","msg":"trace[1443395481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4878; }","duration":"772.206903ms","start":"2026-05-11T01:00:39.533313Z","end":"2026-05-11T01:00:40.30552Z","steps":["trace[1443395481] 'agreement among raft nodes before linearized reading' (duration: 698.567537ms)","trace[1443395481] 'range keys from in-memory index tree' (duration: 73.369328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:40.305544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.262466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-11T01:00:40.305606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:39.533301Z","time spent":"772.294986ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T01:00:40.305645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.094882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T01:00:40.305646Z","caller":"traceutil/trace.go:171","msg":"trace[287678893] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4878; }","duration":"467.41402ms","start":"2026-05-11T01:00:39.838208Z","end":"2026-05-11T01:00:40.305622Z","steps":["trace[287678893] 'agreement among raft nodes before linearized reading' (duration: 393.666161ms)","trace[287678893] 'range keys from in-memory index tree' (duration: 73.558723ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:40.305677Z","caller":"traceutil/trace.go:171","msg":"trace[1603015524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4878; }","duration":"774.161385ms","start":"2026-05-11T01:00:39.531507Z","end":"2026-05-11T01:00:40.305668Z","steps":["trace[1603015524] 'agreement among raft nodes before linearized reading' (duration: 700.381884ms)","trace[1603015524] 'range keys from in-memory index tree' (duration: 73.706149ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:40.305698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:39.531488Z","time spent":"774.206367ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T01:00:40.305696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:39.838176Z","time spent":"467.506073ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-11T01:00:40.517439Z","caller":"traceutil/trace.go:171","msg":"trace[1051508144] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"206.343268ms","start":"2026-05-11T01:00:40.311068Z","end":"2026-05-11T01:00:40.517411Z","steps":["trace[1051508144] 'process raft request' (duration: 206.146601ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:40.65162Z","caller":"traceutil/trace.go:171","msg":"trace[1591107665] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"340.273948ms","start":"2026-05-11T01:00:40.311299Z","end":"2026-05-11T01:00:40.651573Z","steps":["trace[1591107665] 'process raft request' (duration: 302.427547ms)","trace[1591107665] 'compare' (duration: 37.471349ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:40.652246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:40.31126Z","time spent":"340.733403ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:00:40.652297Z","caller":"traceutil/trace.go:171","msg":"trace[543376512] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"339.644018ms","start":"2026-05-11T01:00:40.312584Z","end":"2026-05-11T01:00:40.652228Z","steps":["trace[543376512] 'process raft request' (duration: 338.918875ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:40.652757Z","caller":"traceutil/trace.go:171","msg":"trace[1757403438] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"334.558487ms","start":"2026-05-11T01:00:40.318161Z","end":"2026-05-11T01:00:40.65272Z","steps":["trace[1757403438] 'process raft request' (duration: 333.965538ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:40.652744Z","caller":"traceutil/trace.go:171","msg":"trace[1035077270] linearizableReadLoop","detail":"{readStateIndex:5068; appliedIndex:5064; }","duration":"147.585704ms","start":"2026-05-11T01:00:40.505132Z","end":"2026-05-11T01:00:40.652718Z","steps":["trace[1035077270] 'read index received' (duration: 12.716324ms)","trace[1035077270] 'applied index is now lower than readState.Index' (duration: 134.86809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:40.652789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:40.312574Z","time spent":"340.050692ms","remote":"127.0.0.1:39448","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-05-11T01:00:40.653009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:00:40.318121Z","time spent":"334.688931ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:00:40.653069Z","caller":"traceutil/trace.go:171","msg":"trace[1770701389] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"272.982014ms","start":"2026-05-11T01:00:40.380059Z","end":"2026-05-11T01:00:40.653041Z","steps":["trace[1770701389] 'process raft request' (duration: 272.420575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:40.653689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.535163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:00:40.65375Z","caller":"traceutil/trace.go:171","msg":"trace[1858636428] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:4884; }","duration":"148.674388ms","start":"2026-05-11T01:00:40.505058Z","end":"2026-05-11T01:00:40.653733Z","steps":["trace[1858636428] 'agreement among raft nodes before linearized reading' (duration: 148.537484ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:01:11.638479Z","caller":"traceutil/trace.go:171","msg":"trace[786621655] linearizableReadLoop","detail":"{readStateIndex:5426; appliedIndex:5425; }","duration":"145.073486ms","start":"2026-05-11T01:01:11.493381Z","end":"2026-05-11T01:01:11.638455Z","steps":["trace[786621655] 'read index received' (duration: 144.890659ms)","trace[786621655] 'applied index is now lower than readState.Index' (duration: 181.797µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:01:11.638604Z","caller":"traceutil/trace.go:171","msg":"trace[1633786854] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"145.906828ms","start":"2026-05-11T01:01:11.492617Z","end":"2026-05-11T01:01:11.638524Z","steps":["trace[1633786854] 'process raft request' (duration: 145.669358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:01:11.638855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.43289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"warn","ts":"2026-05-11T01:01:11.638867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.560896ms","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-05-11T01:01:11.638931Z","caller":"traceutil/trace.go:171","msg":"trace[417001320] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5231; }","duration":"145.534464ms","start":"2026-05-11T01:01:11.493357Z","end":"2026-05-11T01:01:11.638892Z","steps":["trace[417001320] 'agreement among raft nodes before linearized reading' (duration: 145.284865ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:01:11.638949Z","caller":"traceutil/trace.go:171","msg":"trace[183411505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5231; }","duration":"141.661969ms","start":"2026-05-11T01:01:11.497267Z","end":"2026-05-11T01:01:11.638929Z","steps":["trace[183411505] 'agreement among raft nodes before linearized reading' (duration: 141.462992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:01:11.639121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.264824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-11T01:01:11.639155Z","caller":"traceutil/trace.go:171","msg":"trace[648001101] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5231; }","duration":"110.308436ms","start":"2026-05-11T01:01:11.528835Z","end":"2026-05-11T01:01:11.639143Z","steps":["trace[648001101] 'agreement among raft nodes before linearized reading' (duration: 110.224343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:01:11.639369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.544194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keystone-api\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-05-11T01:01:11.639578Z","caller":"traceutil/trace.go:171","msg":"trace[1388671732] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keystone-api; range_end:; response_count:1; response_revision:5231; }","duration":"110.775403ms","start":"2026-05-11T01:01:11.528795Z","end":"2026-05-11T01:01:11.63957Z","steps":["trace[1388671732] 'agreement among raft nodes before linearized reading' (duration: 110.515763ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:01:39.052945Z","caller":"traceutil/trace.go:171","msg":"trace[614640467] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"189.592624ms","start":"2026-05-11T01:01:38.863326Z","end":"2026-05-11T01:01:39.052918Z","steps":["trace[614640467] 'process raft request' (duration: 189.353484ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:01:39.052916Z","caller":"traceutil/trace.go:171","msg":"trace[501824553] linearizableReadLoop","detail":"{readStateIndex:5615; appliedIndex:5614; }","duration":"164.03505ms","start":"2026-05-11T01:01:38.888826Z","end":"2026-05-11T01:01:39.052861Z","steps":["trace[501824553] 'read index received' (duration: 163.923935ms)","trace[501824553] 'applied index is now lower than readState.Index' (duration: 109.574µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:01:39.053192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.333372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-05-11T01:01:39.053312Z","caller":"traceutil/trace.go:171","msg":"trace[1932704417] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5414; }","duration":"164.53416ms","start":"2026-05-11T01:01:38.888751Z","end":"2026-05-11T01:01:39.053285Z","steps":["trace[1932704417] 'agreement among raft nodes before linearized reading' (duration: 164.230357ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:01:39.056592Z","caller":"traceutil/trace.go:171","msg":"trace[1434659258] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"166.955631ms","start":"2026-05-11T01:01:38.889615Z","end":"2026-05-11T01:01:39.05657Z","steps":["trace[1434659258] 'process raft request' (duration: 166.061194ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:01:39.057064Z","caller":"traceutil/trace.go:171","msg":"trace[1500697692] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"139.703586ms","start":"2026-05-11T01:01:38.917102Z","end":"2026-05-11T01:01:39.056805Z","steps":["trace[1500697692] 'process raft request' (duration: 138.710025ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:02:00.814216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2924} {"level":"info","ts":"2026-05-11T01:02:00.90793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2924,"took":"92.108277ms","hash":3205376763,"current-db-size-bytes":54001664,"current-db-size":"54 MB","current-db-size-in-use-bytes":29122560,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-11T01:02:00.908006Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3205376763,"revision":2924,"compact-revision":-1} {"level":"info","ts":"2026-05-11T01:04:30.547826Z","caller":"traceutil/trace.go:171","msg":"trace[707403405] transaction","detail":"{read_only:false; response_revision:6606; number_of_response:1; }","duration":"131.180373ms","start":"2026-05-11T01:04:30.41662Z","end":"2026-05-11T01:04:30.547801Z","steps":["trace[707403405] 'process raft request' (duration: 130.974082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:04:48.325837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.880567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-11T01:04:48.326401Z","caller":"traceutil/trace.go:171","msg":"trace[1850044201] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6751; }","duration":"115.715261ms","start":"2026-05-11T01:04:48.210657Z","end":"2026-05-11T01:04:48.326372Z","steps":["trace[1850044201] 'range keys from in-memory index tree' (duration: 114.031002ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:04:48.541165Z","caller":"traceutil/trace.go:171","msg":"trace[1226059663] transaction","detail":"{read_only:false; response_revision:6756; number_of_response:1; }","duration":"114.988552ms","start":"2026-05-11T01:04:48.42615Z","end":"2026-05-11T01:04:48.541139Z","steps":["trace[1226059663] 'process raft request' (duration: 91.903964ms)","trace[1226059663] 'compare' (duration: 22.776682ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:07:00.823353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5588} {"level":"info","ts":"2026-05-11T01:07:00.90304Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5588,"took":"77.740805ms","hash":1921958041,"current-db-size-bytes":54001664,"current-db-size":"54 MB","current-db-size-in-use-bytes":18276352,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-11T01:07:00.903121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1921958041,"revision":5588,"compact-revision":2924}