{"level":"warn","ts":"2026-08-22T12:50:39.339972Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T12:50:39.3401Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-08-22T12:50:39.340193Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T12:50:39.340212Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-08-22T12:50:39.340245Z","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-08-22T12:50:39.341166Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-08-22T12:50:39.34128Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-08-22T12:50:39.353008Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.457764ms"} {"level":"info","ts":"2026-08-22T12:50:39.362694Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-08-22T12:50:39.362805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T12:50:39.362838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-08-22T12:50:39.362851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T12:50:39.362865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-08-22T12:50:39.362936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-08-22T12:50:39.368787Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T12:50:39.37276Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T12:50:39.374096Z","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-08-22T12:50:39.380838Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T12:50:39.3812Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T12:50:39.381285Z","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-08-22T12:50:39.382606Z","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-08-22T12:50:39.382634Z","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-08-22T12:50:39.388767Z","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-08-22T12:50:39.389129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-08-22T12:50:39.389165Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T12:50:39.389277Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-08-22T12:50:39.389341Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-08-22T12:50:39.389369Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-08-22T12:50:39.389278Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T12:50:39.96444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T12:50:39.964545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T12:50:39.964588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-08-22T12:50:39.964616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-08-22T12:50:39.96468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-08-22T12:50:39.964701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-08-22T12:50:39.964816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-08-22T12:50:39.967065Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T12:50:39.967056Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T12:50:39.967073Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T12:50:39.967326Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T12:50:39.967833Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T12:50:39.967897Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T12:50:39.968186Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T12:50:39.968381Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T12:50:39.968439Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T12:50:39.97074Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-08-22T12:50:39.97454Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T12:50:42.657016Z","caller":"traceutil/trace.go:171","msg":"trace[1351711354] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"192.746422ms","start":"2026-08-22T12:50:42.464225Z","end":"2026-08-22T12:50:42.656971Z","steps":["trace[1351711354] 'read index received' (duration: 192.571228ms)","trace[1351711354] 'applied index is now lower than readState.Index' (duration: 173.514µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:50:42.657099Z","caller":"traceutil/trace.go:171","msg":"trace[330478662] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"261.50252ms","start":"2026-08-22T12:50:42.395553Z","end":"2026-08-22T12:50:42.657055Z","steps":["trace[330478662] 'process raft request' (duration: 261.239354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:50:42.65732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.025747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T12:50:42.657422Z","caller":"traceutil/trace.go:171","msg":"trace[942311968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"193.199612ms","start":"2026-08-22T12:50:42.464202Z","end":"2026-08-22T12:50:42.657401Z","steps":["trace[942311968] 'agreement among raft nodes before linearized reading' (duration: 192.936356ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:50:42.938499Z","caller":"traceutil/trace.go:171","msg":"trace[1084770990] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"275.257908ms","start":"2026-08-22T12:50:42.663213Z","end":"2026-08-22T12:50:42.938471Z","steps":["trace[1084770990] 'process raft request' (duration: 190.23891ms)","trace[1084770990] 'compare' (duration: 84.852625ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:50:43.141104Z","caller":"traceutil/trace.go:171","msg":"trace[1072527173] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"175.605417ms","start":"2026-08-22T12:50:42.965463Z","end":"2026-08-22T12:50:43.141069Z","steps":["trace[1072527173] 'process raft request' (duration: 149.514124ms)","trace[1072527173] 'compare' (duration: 25.94803ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T12:51:16.401827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.258067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T12:51:16.402309Z","caller":"traceutil/trace.go:171","msg":"trace[66074171] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"182.868047ms","start":"2026-08-22T12:51:16.219416Z","end":"2026-08-22T12:51:16.402284Z","steps":["trace[66074171] 'process raft request' (duration: 43.91023ms)","trace[66074171] 'compare' (duration: 136.349261ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:51:22.756458Z","caller":"traceutil/trace.go:171","msg":"trace[1488099679] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"152.025449ms","start":"2026-08-22T12:51:22.604397Z","end":"2026-08-22T12:51:22.756423Z","steps":["trace[1488099679] 'process raft request' (duration: 150.796477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:51:23.924277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471728474261981248,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T12:51:23.942292Z","caller":"traceutil/trace.go:171","msg":"trace[1959062279] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"518.376274ms","start":"2026-08-22T12:51:23.42388Z","end":"2026-08-22T12:51:23.942256Z","steps":["trace[1959062279] 'read index received' (duration: 517.202513ms)","trace[1959062279] 'applied index is now lower than readState.Index' (duration: 1.172511ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:51:23.942364Z","caller":"traceutil/trace.go:171","msg":"trace[466303447] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"615.142437ms","start":"2026-08-22T12:51:23.327176Z","end":"2026-08-22T12:51:23.942318Z","steps":["trace[466303447] 'process raft request' (duration: 614.236551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:51:23.942608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:51:23.327123Z","time spent":"615.32785ms","remote":"127.0.0.1:47226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T12:51:23.942653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.73283ms","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-08-22T12:51:23.942898Z","caller":"traceutil/trace.go:171","msg":"trace[1704356391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"519.019264ms","start":"2026-08-22T12:51:23.423839Z","end":"2026-08-22T12:51:23.942858Z","steps":["trace[1704356391] 'agreement among raft nodes before linearized reading' (duration: 518.621437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:51:23.942979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:51:23.423815Z","time spent":"519.143747ms","remote":"127.0.0.1:47424","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-08-22T12:51:23.943332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.396503ms","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-08-22T12:51:23.943443Z","caller":"traceutil/trace.go:171","msg":"trace[343458521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"286.553725ms","start":"2026-08-22T12:51:23.656858Z","end":"2026-08-22T12:51:23.943411Z","steps":["trace[343458521] 'agreement among raft nodes before linearized reading' (duration: 286.066176ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:51:39.454607Z","caller":"traceutil/trace.go:171","msg":"trace[1477272561] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"166.430219ms","start":"2026-08-22T12:51:39.288152Z","end":"2026-08-22T12:51:39.454583Z","steps":["trace[1477272561] 'process raft request' (duration: 166.237066ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:52:09.966626Z","caller":"traceutil/trace.go:171","msg":"trace[1929200135] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"114.134108ms","start":"2026-08-22T12:52:09.852458Z","end":"2026-08-22T12:52:09.966592Z","steps":["trace[1929200135] 'read index received' (duration: 113.823804ms)","trace[1929200135] 'applied index is now lower than readState.Index' (duration: 308.144µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:52:09.966672Z","caller":"traceutil/trace.go:171","msg":"trace[255090560] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"308.336596ms","start":"2026-08-22T12:52:09.658302Z","end":"2026-08-22T12:52:09.966638Z","steps":["trace[255090560] 'process raft request' (duration: 308.019841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:52:09.967753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:52:09.658264Z","time spent":"309.276611ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T12:52:09.966857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.372853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T12:52:09.967936Z","caller":"traceutil/trace.go:171","msg":"trace[859134696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"115.540169ms","start":"2026-08-22T12:52:09.852374Z","end":"2026-08-22T12:52:09.967914Z","steps":["trace[859134696] 'agreement among raft nodes before linearized reading' (duration: 114.354251ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:52:29.360959Z","caller":"traceutil/trace.go:171","msg":"trace[1220219455] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"171.984578ms","start":"2026-08-22T12:52:29.188943Z","end":"2026-08-22T12:52:29.360928Z","steps":["trace[1220219455] 'read index received' (duration: 171.744565ms)","trace[1220219455] 'applied index is now lower than readState.Index' (duration: 238.333µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T12:52:29.361253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.276983ms","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-08-22T12:52:29.361318Z","caller":"traceutil/trace.go:171","msg":"trace[1401517306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1316; }","duration":"172.421595ms","start":"2026-08-22T12:52:29.188879Z","end":"2026-08-22T12:52:29.361301Z","steps":["trace[1401517306] 'agreement among raft nodes before linearized reading' (duration: 172.169741ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:52:29.361398Z","caller":"traceutil/trace.go:171","msg":"trace[153200235] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"221.822982ms","start":"2026-08-22T12:52:29.139532Z","end":"2026-08-22T12:52:29.361355Z","steps":["trace[153200235] 'process raft request' (duration: 221.176682ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:52:30.209985Z","caller":"traceutil/trace.go:171","msg":"trace[1050404886] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"114.469341ms","start":"2026-08-22T12:52:30.095482Z","end":"2026-08-22T12:52:30.209951Z","steps":["trace[1050404886] 'read index received' (duration: 114.366049ms)","trace[1050404886] 'applied index is now lower than readState.Index' (duration: 101.562µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:52:30.210107Z","caller":"traceutil/trace.go:171","msg":"trace[1516978122] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"121.572676ms","start":"2026-08-22T12:52:30.088503Z","end":"2026-08-22T12:52:30.210076Z","steps":["trace[1516978122] 'process raft request' (duration: 121.230361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:52:30.210393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.889257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-22T12:52:30.210453Z","caller":"traceutil/trace.go:171","msg":"trace[1154807329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1318; }","duration":"115.014599ms","start":"2026-08-22T12:52:30.095423Z","end":"2026-08-22T12:52:30.210438Z","steps":["trace[1154807329] 'agreement among raft nodes before linearized reading' (duration: 114.670854ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:52:35.190822Z","caller":"traceutil/trace.go:171","msg":"trace[7836690] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"198.211611ms","start":"2026-08-22T12:52:34.992574Z","end":"2026-08-22T12:52:35.190785Z","steps":["trace[7836690] 'process raft request' (duration: 197.936627ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:52:36.49204Z","caller":"traceutil/trace.go:171","msg":"trace[281672471] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"248.574919ms","start":"2026-08-22T12:52:36.243437Z","end":"2026-08-22T12:52:36.492012Z","steps":["trace[281672471] 'read index received' (duration: 248.398236ms)","trace[281672471] 'applied index is now lower than readState.Index' (duration: 175.143µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:52:36.492198Z","caller":"traceutil/trace.go:171","msg":"trace[1452438330] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"250.327494ms","start":"2026-08-22T12:52:36.241829Z","end":"2026-08-22T12:52:36.492156Z","steps":["trace[1452438330] 'process raft request' (duration: 249.986679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:52:36.492353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.897893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-22T12:52:36.492398Z","caller":"traceutil/trace.go:171","msg":"trace[94063343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1438; }","duration":"248.992825ms","start":"2026-08-22T12:52:36.243393Z","end":"2026-08-22T12:52:36.492386Z","steps":["trace[94063343] 'agreement among raft nodes before linearized reading' (duration: 248.766492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:01.766439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471728474261983709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T12:53:02.267574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471728474261983709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T12:53:02.357568Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.143754528s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T12:53:02.358029Z","caller":"traceutil/trace.go:171","msg":"trace[212871000] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"1.144408617s","start":"2026-08-22T12:53:01.213592Z","end":"2026-08-22T12:53:02.358001Z","steps":["trace[212871000] 'process raft request' (duration: 1.144231854s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.358384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:01.213566Z","time spent":"1.144583259s","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T12:53:02.366849Z","caller":"traceutil/trace.go:171","msg":"trace[207785921] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1926; }","duration":"1.101469849s","start":"2026-08-22T12:53:01.265368Z","end":"2026-08-22T12:53:02.366838Z","steps":["trace[207785921] 'read index received' (duration: 1.092419331s)","trace[207785921] 'applied index is now lower than readState.Index' (duration: 9.049858ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:53:02.366973Z","caller":"traceutil/trace.go:171","msg":"trace[1715411595] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"216.719543ms","start":"2026-08-22T12:53:02.150246Z","end":"2026-08-22T12:53:02.366966Z","steps":["trace[1715411595] 'process raft request' (duration: 216.537901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.367167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101811414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-08-22T12:53:02.367238Z","caller":"traceutil/trace.go:171","msg":"trace[665001915] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1857; }","duration":"1.101985636s","start":"2026-08-22T12:53:01.265241Z","end":"2026-08-22T12:53:02.367226Z","steps":["trace[665001915] 'agreement among raft nodes before linearized reading' (duration: 1.101819804s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.367306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:01.265182Z","time spent":"1.102108898s","remote":"127.0.0.1:47312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10692,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-08-22T12:53:02.367444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.883267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-70fe0d82-2d92-492b-b337-71dc6c92579f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T12:53:02.367497Z","caller":"traceutil/trace.go:171","msg":"trace[310696308] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-70fe0d82-2d92-492b-b337-71dc6c92579f; range_end:; response_count:1; response_revision:1857; }","duration":"185.970929ms","start":"2026-08-22T12:53:02.181506Z","end":"2026-08-22T12:53:02.367477Z","steps":["trace[310696308] 'agreement among raft nodes before linearized reading' (duration: 185.871377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.367313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.394022ms","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-08-22T12:53:02.367684Z","caller":"traceutil/trace.go:171","msg":"trace[916546141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1857; }","duration":"798.825017ms","start":"2026-08-22T12:53:01.568846Z","end":"2026-08-22T12:53:02.367671Z","steps":["trace[916546141] 'agreement among raft nodes before linearized reading' (duration: 798.33385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.367742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.99404ms","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-08-22T12:53:02.367782Z","caller":"traceutil/trace.go:171","msg":"trace[1093209449] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1857; }","duration":"399.074632ms","start":"2026-08-22T12:53:01.968698Z","end":"2026-08-22T12:53:02.367773Z","steps":["trace[1093209449] 'agreement among raft nodes before linearized reading' (duration: 398.96476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.367813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:01.968674Z","time spent":"399.130342ms","remote":"127.0.0.1:50220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T12:53:02.367781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.544084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T12:53:02.367848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:01.568816Z","time spent":"798.98196ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T12:53:02.367641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.887646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T12:53:02.368314Z","caller":"traceutil/trace.go:171","msg":"trace[1143142834] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1857; }","duration":"461.613376ms","start":"2026-08-22T12:53:01.906686Z","end":"2026-08-22T12:53:02.368299Z","steps":["trace[1143142834] 'agreement among raft nodes before linearized reading' (duration: 460.905266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.368377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:01.906664Z","time spent":"461.690597ms","remote":"127.0.0.1:49940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-08-22T12:53:02.368911Z","caller":"traceutil/trace.go:171","msg":"trace[276145585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"516.792037ms","start":"2026-08-22T12:53:01.851142Z","end":"2026-08-22T12:53:02.367934Z","steps":["trace[276145585] 'agreement among raft nodes before linearized reading' (duration: 516.539264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.369565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:01.851122Z","time spent":"518.41207ms","remote":"127.0.0.1:47140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T12:53:02.825318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.453212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T12:53:02.825588Z","caller":"traceutil/trace.go:171","msg":"trace[863533790] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"355.14814ms","start":"2026-08-22T12:53:02.470396Z","end":"2026-08-22T12:53:02.825544Z","steps":["trace[863533790] 'process raft request' (duration: 239.357313ms)","trace[863533790] 'compare' (duration: 115.223379ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:53:02.825707Z","caller":"traceutil/trace.go:171","msg":"trace[1410629395] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"353.140772ms","start":"2026-08-22T12:53:02.472533Z","end":"2026-08-22T12:53:02.825673Z","steps":["trace[1410629395] 'process raft request' (duration: 352.897818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.825799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:02.470384Z","time spent":"355.316362ms","remote":"127.0.0.1:47440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T12:53:02.825906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:02.472523Z","time spent":"353.269353ms","remote":"127.0.0.1:47290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T12:53:02.825729Z","caller":"traceutil/trace.go:171","msg":"trace[258113441] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"353.00166ms","start":"2026-08-22T12:53:02.472713Z","end":"2026-08-22T12:53:02.825714Z","steps":["trace[258113441] 'process raft request' (duration: 352.792967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.826109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:02.472687Z","time spent":"353.391025ms","remote":"127.0.0.1:47312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T12:53:02.826109Z","caller":"traceutil/trace.go:171","msg":"trace[479825347] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"353.328344ms","start":"2026-08-22T12:53:02.472757Z","end":"2026-08-22T12:53:02.826086Z","steps":["trace[479825347] 'process raft request' (duration: 352.908389ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:53:02.826675Z","caller":"traceutil/trace.go:171","msg":"trace[513788218] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"214.198208ms","start":"2026-08-22T12:53:02.612462Z","end":"2026-08-22T12:53:02.82666Z","steps":["trace[513788218] 'process raft request' (duration: 213.67652ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:53:02.826773Z","caller":"traceutil/trace.go:171","msg":"trace[974371239] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1931; }","duration":"174.109041ms","start":"2026-08-22T12:53:02.65265Z","end":"2026-08-22T12:53:02.826759Z","steps":["trace[974371239] 'read index received' (duration: 57.237749ms)","trace[974371239] 'applied index is now lower than readState.Index' (duration: 116.869982ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:53:02.826734Z","caller":"traceutil/trace.go:171","msg":"trace[1232687904] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"343.934241ms","start":"2026-08-22T12:53:02.482779Z","end":"2026-08-22T12:53:02.826713Z","steps":["trace[1232687904] 'process raft request' (duration: 343.195831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.826877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:02.482769Z","time spent":"344.047674ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T12:53:02.826978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.337204ms","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-08-22T12:53:02.827016Z","caller":"traceutil/trace.go:171","msg":"trace[152009123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1867; }","duration":"174.428196ms","start":"2026-08-22T12:53:02.652579Z","end":"2026-08-22T12:53:02.827007Z","steps":["trace[152009123] 'agreement among raft nodes before linearized reading' (duration: 174.272193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.827066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.071878ms","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-08-22T12:53:02.82717Z","caller":"traceutil/trace.go:171","msg":"trace[210734059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1867; }","duration":"171.22362ms","start":"2026-08-22T12:53:02.655919Z","end":"2026-08-22T12:53:02.827143Z","steps":["trace[210734059] 'agreement among raft nodes before linearized reading' (duration: 170.975417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.82719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.990581ms","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-08-22T12:53:02.827224Z","caller":"traceutil/trace.go:171","msg":"trace[1222813602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1867; }","duration":"155.040311ms","start":"2026-08-22T12:53:02.672174Z","end":"2026-08-22T12:53:02.827214Z","steps":["trace[1222813602] 'agreement among raft nodes before linearized reading' (duration: 154.95582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:02.82991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:02.472748Z","time spent":"357.082408ms","remote":"127.0.0.1:47440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T12:53:14.20814Z","caller":"traceutil/trace.go:171","msg":"trace[1008754702] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"317.626735ms","start":"2026-08-22T12:53:13.89044Z","end":"2026-08-22T12:53:14.208067Z","steps":["trace[1008754702] 'process raft request' (duration: 274.901718ms)","trace[1008754702] 'compare' (duration: 42.247171ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T12:53:14.208328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:13.890421Z","time spent":"317.800178ms","remote":"127.0.0.1:47168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T12:53:35.0796Z","caller":"traceutil/trace.go:171","msg":"trace[277888741] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"126.511644ms","start":"2026-08-22T12:53:34.953035Z","end":"2026-08-22T12:53:35.079546Z","steps":["trace[277888741] 'process raft request' (duration: 126.290105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:40.745736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.087872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T12:53:40.745927Z","caller":"traceutil/trace.go:171","msg":"trace[81964948] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"152.864653ms","start":"2026-08-22T12:53:40.593037Z","end":"2026-08-22T12:53:40.745902Z","steps":["trace[81964948] 'read index received' (duration: 4.404177ms)","trace[81964948] 'applied index is now lower than readState.Index' (duration: 148.458036ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:53:40.746035Z","caller":"traceutil/trace.go:171","msg":"trace[1247095256] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"199.345539ms","start":"2026-08-22T12:53:40.546675Z","end":"2026-08-22T12:53:40.74602Z","steps":["trace[1247095256] 'process raft request' (duration: 50.873082ms)","trace[1247095256] 'compare' (duration: 147.968427ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T12:53:40.746693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.634265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-22T12:53:40.74675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.648609ms","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-08-22T12:53:40.747046Z","caller":"traceutil/trace.go:171","msg":"trace[1074366626] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2123; }","duration":"102.001793ms","start":"2026-08-22T12:53:40.645006Z","end":"2026-08-22T12:53:40.747008Z","steps":["trace[1074366626] 'agreement among raft nodes before linearized reading' (duration: 101.304225ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:53:40.746807Z","caller":"traceutil/trace.go:171","msg":"trace[1551205886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2123; }","duration":"153.796981ms","start":"2026-08-22T12:53:40.592979Z","end":"2026-08-22T12:53:40.746776Z","steps":["trace[1551205886] 'agreement among raft nodes before linearized reading' (duration: 153.354084ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:53:40.87014Z","caller":"traceutil/trace.go:171","msg":"trace[41283902] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"118.181511ms","start":"2026-08-22T12:53:40.751917Z","end":"2026-08-22T12:53:40.870098Z","steps":["trace[41283902] 'process raft request' (duration: 104.68697ms)","trace[41283902] 'compare' (duration: 13.237481ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:53:41.376312Z","caller":"traceutil/trace.go:171","msg":"trace[1857006258] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"188.675456ms","start":"2026-08-22T12:53:41.187613Z","end":"2026-08-22T12:53:41.376288Z","steps":["trace[1857006258] 'process raft request' (duration: 129.559735ms)","trace[1857006258] 'compare' (duration: 58.627791ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:53:41.376567Z","caller":"traceutil/trace.go:171","msg":"trace[13506999] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"188.378274ms","start":"2026-08-22T12:53:41.188171Z","end":"2026-08-22T12:53:41.376549Z","steps":["trace[13506999] 'process raft request' (duration: 187.866714ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:53:41.37621Z","caller":"traceutil/trace.go:171","msg":"trace[809449884] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"186.980329ms","start":"2026-08-22T12:53:41.189179Z","end":"2026-08-22T12:53:41.376159Z","steps":["trace[809449884] 'process raft request' (duration: 186.927927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:42.162447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.685505ms","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-08-22T12:53:42.162529Z","caller":"traceutil/trace.go:171","msg":"trace[1137166542] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"408.82249ms","start":"2026-08-22T12:53:41.753686Z","end":"2026-08-22T12:53:42.162508Z","steps":["trace[1137166542] 'range keys from in-memory index tree' (duration: 408.487137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:42.162573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:53:41.753662Z","time spent":"408.900183ms","remote":"127.0.0.1:50220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-22T12:53:42.934948Z","caller":"traceutil/trace.go:171","msg":"trace[1741208780] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"182.227145ms","start":"2026-08-22T12:53:42.752687Z","end":"2026-08-22T12:53:42.934914Z","steps":["trace[1741208780] 'process raft request' (duration: 182.056208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:43.470216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.62678ms","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-08-22T12:53:43.470311Z","caller":"traceutil/trace.go:171","msg":"trace[1914279276] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2133; }","duration":"298.783866ms","start":"2026-08-22T12:53:43.171504Z","end":"2026-08-22T12:53:43.470288Z","steps":["trace[1914279276] 'range keys from in-memory index tree' (duration: 298.339388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:53:43.470506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.474306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T12:53:43.470644Z","caller":"traceutil/trace.go:171","msg":"trace[1630012545] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"293.654623ms","start":"2026-08-22T12:53:43.176969Z","end":"2026-08-22T12:53:43.470624Z","steps":["trace[1630012545] 'process raft request' (duration: 49.889505ms)","trace[1630012545] 'compare' (duration: 242.953906ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:53:43.472046Z","caller":"traceutil/trace.go:171","msg":"trace[1901300253] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2214; }","duration":"221.016548ms","start":"2026-08-22T12:53:43.251005Z","end":"2026-08-22T12:53:43.472021Z","steps":["trace[1901300253] 'read index received' (duration: 220.780789ms)","trace[1901300253] 'applied index is now lower than readState.Index' (duration: 234.729µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T12:53:43.472393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.394052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-22T12:53:43.472449Z","caller":"traceutil/trace.go:171","msg":"trace[1833393045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2136; }","duration":"221.504438ms","start":"2026-08-22T12:53:43.250931Z","end":"2026-08-22T12:53:43.472435Z","steps":["trace[1833393045] 'agreement among raft nodes before linearized reading' (duration: 221.284729ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:53:43.4725Z","caller":"traceutil/trace.go:171","msg":"trace[1640255584] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"284.740651ms","start":"2026-08-22T12:53:43.187745Z","end":"2026-08-22T12:53:43.472486Z","steps":["trace[1640255584] 'process raft request' (duration: 284.108256ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:53:43.472781Z","caller":"traceutil/trace.go:171","msg":"trace[254510714] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"283.880697ms","start":"2026-08-22T12:53:43.188882Z","end":"2026-08-22T12:53:43.472763Z","steps":["trace[254510714] 'process raft request' (duration: 283.101916ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:53:43.661832Z","caller":"traceutil/trace.go:171","msg":"trace[1288589837] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"185.654028ms","start":"2026-08-22T12:53:43.476125Z","end":"2026-08-22T12:53:43.661779Z","steps":["trace[1288589837] 'process raft request' (duration: 148.518908ms)","trace[1288589837] 'compare' (duration: 36.949523ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:53:43.662376Z","caller":"traceutil/trace.go:171","msg":"trace[245720351] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"185.362136ms","start":"2026-08-22T12:53:43.476983Z","end":"2026-08-22T12:53:43.662345Z","steps":["trace[245720351] 'process raft request' (duration: 185.058304ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:53:43.662443Z","caller":"traceutil/trace.go:171","msg":"trace[640595719] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"185.222152ms","start":"2026-08-22T12:53:43.477206Z","end":"2026-08-22T12:53:43.662428Z","steps":["trace[640595719] 'process raft request' (duration: 185.074766ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:53:43.662836Z","caller":"traceutil/trace.go:171","msg":"trace[1758537414] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"185.363406ms","start":"2026-08-22T12:53:43.476878Z","end":"2026-08-22T12:53:43.662242Z","steps":["trace[1758537414] 'process raft request' (duration: 184.858166ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:54:23.941439Z","caller":"traceutil/trace.go:171","msg":"trace[183053829] transaction","detail":"{read_only:false; response_revision:2406; number_of_response:1; }","duration":"193.618752ms","start":"2026-08-22T12:54:23.747777Z","end":"2026-08-22T12:54:23.941396Z","steps":["trace[183053829] 'process raft request' (duration: 193.2764ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:54:32.642766Z","caller":"traceutil/trace.go:171","msg":"trace[402874379] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"227.874519ms","start":"2026-08-22T12:54:32.414863Z","end":"2026-08-22T12:54:32.642738Z","steps":["trace[402874379] 'process raft request' (duration: 227.59156ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:54:33.490927Z","caller":"traceutil/trace.go:171","msg":"trace[839751010] linearizableReadLoop","detail":"{readStateIndex:2603; appliedIndex:2602; }","duration":"393.089426ms","start":"2026-08-22T12:54:33.09781Z","end":"2026-08-22T12:54:33.490899Z","steps":["trace[839751010] 'read index received' (duration: 392.844388ms)","trace[839751010] 'applied index is now lower than readState.Index' (duration: 243.348µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:54:33.491008Z","caller":"traceutil/trace.go:171","msg":"trace[654801347] transaction","detail":"{read_only:false; response_revision:2511; number_of_response:1; }","duration":"476.154858ms","start":"2026-08-22T12:54:33.014817Z","end":"2026-08-22T12:54:33.490972Z","steps":["trace[654801347] 'process raft request' (duration: 475.89957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:54:33.491115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.282102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T12:54:33.491158Z","caller":"traceutil/trace.go:171","msg":"trace[72146687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2511; }","duration":"393.410046ms","start":"2026-08-22T12:54:33.097738Z","end":"2026-08-22T12:54:33.491148Z","steps":["trace[72146687] 'agreement among raft nodes before linearized reading' (duration: 393.299483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:54:33.491198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:54:33.097707Z","time spent":"393.483098ms","remote":"127.0.0.1:47140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T12:54:33.491218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:54:33.014789Z","time spent":"476.327014ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T12:54:33.492253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.318212ms","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-08-22T12:54:33.492335Z","caller":"traceutil/trace.go:171","msg":"trace[1856477575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2512; }","duration":"263.416535ms","start":"2026-08-22T12:54:33.228898Z","end":"2026-08-22T12:54:33.492315Z","steps":["trace[1856477575] 'agreement among raft nodes before linearized reading' (duration: 263.162817ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:54:33.492634Z","caller":"traceutil/trace.go:171","msg":"trace[461032047] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"284.528464ms","start":"2026-08-22T12:54:33.208095Z","end":"2026-08-22T12:54:33.492624Z","steps":["trace[461032047] 'process raft request' (duration: 283.836941ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:54:59.722351Z","caller":"traceutil/trace.go:171","msg":"trace[1494029021] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"105.547585ms","start":"2026-08-22T12:54:59.616767Z","end":"2026-08-22T12:54:59.722315Z","steps":["trace[1494029021] 'process raft request' (duration: 34.528343ms)","trace[1494029021] 'compare' (duration: 70.507577ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:55:06.22599Z","caller":"traceutil/trace.go:171","msg":"trace[495525391] linearizableReadLoop","detail":"{readStateIndex:2790; appliedIndex:2789; }","duration":"459.453387ms","start":"2026-08-22T12:55:05.76651Z","end":"2026-08-22T12:55:06.225963Z","steps":["trace[495525391] 'read index received' (duration: 459.311463ms)","trace[495525391] 'applied index is now lower than readState.Index' (duration: 140.464µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:55:06.226427Z","caller":"traceutil/trace.go:171","msg":"trace[2023061977] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"588.914998ms","start":"2026-08-22T12:55:05.63747Z","end":"2026-08-22T12:55:06.226385Z","steps":["trace[2023061977] 'process raft request' (duration: 588.346892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:55:06.226656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:55:05.637442Z","time spent":"589.096913ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T12:55:06.226697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.020353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-22T12:55:06.226869Z","caller":"traceutil/trace.go:171","msg":"trace[1151166683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2690; }","duration":"460.352193ms","start":"2026-08-22T12:55:05.766451Z","end":"2026-08-22T12:55:06.226803Z","steps":["trace[1151166683] 'agreement among raft nodes before linearized reading' (duration: 459.88874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:55:06.226983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:55:05.766423Z","time spent":"460.545668ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T12:55:06.339561Z","caller":"traceutil/trace.go:171","msg":"trace[146467730] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"548.876667ms","start":"2026-08-22T12:55:05.79066Z","end":"2026-08-22T12:55:06.339537Z","steps":["trace[146467730] 'process raft request' (duration: 487.731755ms)","trace[146467730] 'compare' (duration: 60.647738ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T12:55:06.339717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:55:05.790617Z","time spent":"549.018601ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T12:55:06.339818Z","caller":"traceutil/trace.go:171","msg":"trace[180637588] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"535.596905ms","start":"2026-08-22T12:55:05.804182Z","end":"2026-08-22T12:55:06.339779Z","steps":["trace[180637588] 'process raft request' (duration: 534.981978ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:55:06.339892Z","caller":"traceutil/trace.go:171","msg":"trace[875095818] linearizableReadLoop","detail":"{readStateIndex:2793; appliedIndex:2790; }","duration":"113.728829ms","start":"2026-08-22T12:55:06.226151Z","end":"2026-08-22T12:55:06.33988Z","steps":["trace[875095818] 'read index received' (duration: 52.254128ms)","trace[875095818] 'applied index is now lower than readState.Index' (duration: 61.473171ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T12:55:06.34002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:55:05.804147Z","time spent":"535.75484ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T12:55:06.340128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.651857ms","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-08-22T12:55:06.339822Z","caller":"traceutil/trace.go:171","msg":"trace[1870077715] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"105.862214ms","start":"2026-08-22T12:55:06.233929Z","end":"2026-08-22T12:55:06.339791Z","steps":["trace[1870077715] 'process raft request' (duration: 105.785271ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:55:06.340171Z","caller":"traceutil/trace.go:171","msg":"trace[1114407233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2694; }","duration":"105.74499ms","start":"2026-08-22T12:55:06.234417Z","end":"2026-08-22T12:55:06.340162Z","steps":["trace[1114407233] 'agreement among raft nodes before linearized reading' (duration: 105.561355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:55:06.340228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.703054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ce21d196d8878e\" ","response":"range_response_count:1 size:781"} {"level":"warn","ts":"2026-08-22T12:55:06.340214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.060047ms","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-08-22T12:55:06.340344Z","caller":"traceutil/trace.go:171","msg":"trace[1406082816] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ce21d196d8878e; range_end:; response_count:1; response_revision:2694; }","duration":"161.857868ms","start":"2026-08-22T12:55:06.178438Z","end":"2026-08-22T12:55:06.340295Z","steps":["trace[1406082816] 'agreement among raft nodes before linearized reading' (duration: 161.531739ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:55:06.340338Z","caller":"traceutil/trace.go:171","msg":"trace[1129858015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2694; }","duration":"152.302434ms","start":"2026-08-22T12:55:06.188016Z","end":"2026-08-22T12:55:06.340318Z","steps":["trace[1129858015] 'agreement among raft nodes before linearized reading' (duration: 151.941823ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:55:06.33985Z","caller":"traceutil/trace.go:171","msg":"trace[732617835] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"522.488078ms","start":"2026-08-22T12:55:05.817331Z","end":"2026-08-22T12:55:06.339819Z","steps":["trace[732617835] 'process raft request' (duration: 522.280062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:55:06.340928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:55:05.817311Z","time spent":"523.550418ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T12:55:30.52962Z","caller":"traceutil/trace.go:171","msg":"trace[1468767840] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"101.931602ms","start":"2026-08-22T12:55:30.427666Z","end":"2026-08-22T12:55:30.529598Z","steps":["trace[1468767840] 'process raft request' (duration: 101.12678ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:55:30.536296Z","caller":"traceutil/trace.go:171","msg":"trace[1525963412] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"106.464723ms","start":"2026-08-22T12:55:30.429802Z","end":"2026-08-22T12:55:30.536266Z","steps":["trace[1525963412] 'process raft request' (duration: 106.378411ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:55:30.536607Z","caller":"traceutil/trace.go:171","msg":"trace[1478172747] transaction","detail":"{read_only:false; response_revision:2816; number_of_response:1; }","duration":"108.440395ms","start":"2026-08-22T12:55:30.428154Z","end":"2026-08-22T12:55:30.536595Z","steps":["trace[1478172747] 'process raft request' (duration: 107.86208ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:58:54.666372Z","caller":"traceutil/trace.go:171","msg":"trace[653662209] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"111.853145ms","start":"2026-08-22T12:58:54.554464Z","end":"2026-08-22T12:58:54.666317Z","steps":["trace[653662209] 'process raft request' (duration: 111.54394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:59:00.957809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.890365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T12:59:00.957906Z","caller":"traceutil/trace.go:171","msg":"trace[1490184106] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4638; }","duration":"150.030148ms","start":"2026-08-22T12:59:00.807853Z","end":"2026-08-22T12:59:00.957883Z","steps":["trace[1490184106] 'range keys from in-memory index tree' (duration: 149.738502ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:59:01.422709Z","caller":"traceutil/trace.go:171","msg":"trace[2056095441] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"209.808798ms","start":"2026-08-22T12:59:01.212871Z","end":"2026-08-22T12:59:01.42268Z","steps":["trace[2056095441] 'process raft request' (duration: 209.631245ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:59:01.569057Z","caller":"traceutil/trace.go:171","msg":"trace[608638368] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"353.700048ms","start":"2026-08-22T12:59:01.21532Z","end":"2026-08-22T12:59:01.56902Z","steps":["trace[608638368] 'process raft request' (duration: 353.396272ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:59:01.569088Z","caller":"traceutil/trace.go:171","msg":"trace[674209984] linearizableReadLoop","detail":"{readStateIndex:4821; appliedIndex:4820; }","duration":"352.494966ms","start":"2026-08-22T12:59:01.216562Z","end":"2026-08-22T12:59:01.569057Z","steps":["trace[674209984] 'read index received' (duration: 206.108999ms)","trace[674209984] 'applied index is now lower than readState.Index' (duration: 146.384507ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T12:59:01.569223Z","caller":"traceutil/trace.go:171","msg":"trace[1783582358] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"252.892751ms","start":"2026-08-22T12:59:01.316315Z","end":"2026-08-22T12:59:01.569208Z","steps":["trace[1783582358] 'process raft request' (duration: 252.691367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:59:01.569261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:59:01.215287Z","time spent":"353.844491ms","remote":"127.0.0.1:47206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T12:59:01.569338Z","caller":"traceutil/trace.go:171","msg":"trace[710512446] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"253.36383ms","start":"2026-08-22T12:59:01.315737Z","end":"2026-08-22T12:59:01.569101Z","steps":["trace[710512446] 'process raft request' (duration: 253.230507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:59:01.569425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.873663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T12:59:01.569469Z","caller":"traceutil/trace.go:171","msg":"trace[1729309817] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:0; response_revision:4648; }","duration":"352.970905ms","start":"2026-08-22T12:59:01.216488Z","end":"2026-08-22T12:59:01.569459Z","steps":["trace[1729309817] 'agreement among raft nodes before linearized reading' (duration: 352.880334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:59:01.569502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:59:01.216474Z","time spent":"353.018826ms","remote":"127.0.0.1:47226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" "} {"level":"info","ts":"2026-08-22T12:59:01.569747Z","caller":"traceutil/trace.go:171","msg":"trace[941663593] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"257.646179ms","start":"2026-08-22T12:59:01.312087Z","end":"2026-08-22T12:59:01.569733Z","steps":["trace[941663593] 'process raft request' (duration: 256.821374ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:59:01.587535Z","caller":"traceutil/trace.go:171","msg":"trace[307257255] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"157.232659ms","start":"2026-08-22T12:59:01.430285Z","end":"2026-08-22T12:59:01.587518Z","steps":["trace[307257255] 'process raft request' (duration: 157.131257ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:59:01.587787Z","caller":"traceutil/trace.go:171","msg":"trace[1181477179] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"158.730907ms","start":"2026-08-22T12:59:01.428995Z","end":"2026-08-22T12:59:01.587726Z","steps":["trace[1181477179] 'process raft request' (duration: 158.317929ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:59:01.587951Z","caller":"traceutil/trace.go:171","msg":"trace[378840169] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"156.812071ms","start":"2026-08-22T12:59:01.431097Z","end":"2026-08-22T12:59:01.587909Z","steps":["trace[378840169] 'process raft request' (duration: 156.382133ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:59:01.588003Z","caller":"traceutil/trace.go:171","msg":"trace[1809987783] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"155.473316ms","start":"2026-08-22T12:59:01.432432Z","end":"2026-08-22T12:59:01.587905Z","steps":["trace[1809987783] 'process raft request' (duration: 155.359884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:59:01.588162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.690173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-08-22T12:59:01.588318Z","caller":"traceutil/trace.go:171","msg":"trace[250255417] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4652; }","duration":"161.008669ms","start":"2026-08-22T12:59:01.427292Z","end":"2026-08-22T12:59:01.5883Z","steps":["trace[250255417] 'agreement among raft nodes before linearized reading' (duration: 160.751305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:59:01.588301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.893827ms","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-08-22T12:59:01.588461Z","caller":"traceutil/trace.go:171","msg":"trace[635208486] 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:4652; }","duration":"322.036849ms","start":"2026-08-22T12:59:01.266363Z","end":"2026-08-22T12:59:01.5884Z","steps":["trace[635208486] 'agreement among raft nodes before linearized reading' (duration: 321.653022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T12:59:01.588626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T12:59:01.266353Z","time spent":"322.258263ms","remote":"127.0.0.1:47288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-08-22T13:00:40.096753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2867} {"level":"info","ts":"2026-08-22T13:00:40.183821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2867,"took":"84.937056ms","hash":3831430078,"current-db-size-bytes":54792192,"current-db-size":"55 MB","current-db-size-in-use-bytes":29212672,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-22T13:00:40.183903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3831430078,"revision":2867,"compact-revision":-1} {"level":"info","ts":"2026-08-22T13:05:40.103608Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5549} {"level":"info","ts":"2026-08-22T13:05:40.212202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5549,"took":"106.274748ms","hash":2557775055,"current-db-size-bytes":54792192,"current-db-size":"55 MB","current-db-size-in-use-bytes":18116608,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-22T13:05:40.212406Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2557775055,"revision":5549,"compact-revision":2867}