{"level":"warn","ts":"2026-04-08T00:33:51.87392Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:33:51.874036Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19: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.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-04-08T00:33:51.874125Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:33:51.874145Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-08T00:33:51.874168Z","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-04-08T00:33:51.875008Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-04-08T00:33:51.875101Z","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":16,"max-cpu-available":16,"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.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-04-08T00:33:51.878987Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.594126ms"} {"level":"info","ts":"2026-04-08T00:33:51.884686Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-04-08T00:33:51.88477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T00:33:51.884794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-04-08T00:33:51.884808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T00:33:51.884818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-04-08T00:33:51.884871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-04-08T00:33:51.889432Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T00:33:51.890663Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T00:33:51.89187Z","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-04-08T00:33:51.894027Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T00:33:51.894195Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T00:33:51.894351Z","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-04-08T00:33:51.894512Z","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-04-08T00:33:51.894563Z","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-04-08T00:33:51.895994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-04-08T00:33:51.89616Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-08T00:33:51.898036Z","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-04-08T00:33:51.898239Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-08T00:33:51.898339Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T00:33:51.898352Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-08T00:33:51.898276Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T00:33:51.985973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T00:33:51.986051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T00:33:51.986115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-04-08T00:33:51.986151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-04-08T00:33:51.986167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-08T00:33:51.986191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-04-08T00:33:51.986218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-08T00:33:51.98732Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:51.988119Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T00:33:51.988122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:33:51.988176Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:33:51.988655Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:51.988865Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:51.988922Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:51.989095Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T00:33:51.989259Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T00:33:51.991072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-04-08T00:33:51.991889Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T00:34:13.744005Z","caller":"traceutil/trace.go:171","msg":"trace[128128262] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"102.711622ms","start":"2026-04-08T00:34:13.641268Z","end":"2026-04-08T00:34:13.74398Z","steps":["trace[128128262] 'process raft request' (duration: 102.565809ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:23.735331Z","caller":"traceutil/trace.go:171","msg":"trace[788269035] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"166.649239ms","start":"2026-04-08T00:34:23.568657Z","end":"2026-04-08T00:34:23.735307Z","steps":["trace[788269035] 'process raft request' (duration: 166.500486ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:23.794137Z","caller":"traceutil/trace.go:171","msg":"trace[1182752233] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"133.500578ms","start":"2026-04-08T00:34:23.660614Z","end":"2026-04-08T00:34:23.794114Z","steps":["trace[1182752233] 'process raft request' (duration: 132.985796ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:28.829488Z","caller":"traceutil/trace.go:171","msg":"trace[583588887] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"132.452782ms","start":"2026-04-08T00:34:28.696977Z","end":"2026-04-08T00:34:28.82943Z","steps":["trace[583588887] 'read index received' (duration: 127.303067ms)","trace[583588887] 'applied index is now lower than readState.Index' (duration: 5.148635ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:28.829644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.648946ms","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-04-08T00:34:28.829623Z","caller":"traceutil/trace.go:171","msg":"trace[1841510364] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"141.149513ms","start":"2026-04-08T00:34:28.688402Z","end":"2026-04-08T00:34:28.829552Z","steps":["trace[1841510364] 'process raft request' (duration: 136.690605ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:28.829718Z","caller":"traceutil/trace.go:171","msg":"trace[524169726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"132.766229ms","start":"2026-04-08T00:34:28.69694Z","end":"2026-04-08T00:34:28.829706Z","steps":["trace[524169726] 'agreement among raft nodes before linearized reading' (duration: 132.633306ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:29.016593Z","caller":"traceutil/trace.go:171","msg":"trace[9578387] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"182.883231ms","start":"2026-04-08T00:34:28.833674Z","end":"2026-04-08T00:34:29.016558Z","steps":["trace[9578387] 'process raft request' (duration: 126.552949ms)","trace[9578387] 'compare' (duration: 55.906462ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:29.016587Z","caller":"traceutil/trace.go:171","msg":"trace[2022168946] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"133.897097ms","start":"2026-04-08T00:34:28.882663Z","end":"2026-04-08T00:34:29.01656Z","steps":["trace[2022168946] 'process raft request' (duration: 133.819006ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:29.016703Z","caller":"traceutil/trace.go:171","msg":"trace[1957070607] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"155.157681ms","start":"2026-04-08T00:34:28.861506Z","end":"2026-04-08T00:34:29.016664Z","steps":["trace[1957070607] 'read index received' (duration: 98.738317ms)","trace[1957070607] 'applied index is now lower than readState.Index' (duration: 56.416784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:29.016967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.445467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-th254\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-08T00:34:29.01702Z","caller":"traceutil/trace.go:171","msg":"trace[1809228785] range","detail":"{range_begin:/registry/pods/kube-system/cilium-th254; range_end:; response_count:1; response_revision:619; }","duration":"155.54886ms","start":"2026-04-08T00:34:28.861454Z","end":"2026-04-08T00:34:29.017003Z","steps":["trace[1809228785] 'agreement among raft nodes before linearized reading' (duration: 155.416717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.631531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.518406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:34:29.631656Z","caller":"traceutil/trace.go:171","msg":"trace[450587500] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"536.762618ms","start":"2026-04-08T00:34:29.094874Z","end":"2026-04-08T00:34:29.631637Z","steps":["trace[450587500] 'read index received' (duration: 424.469062ms)","trace[450587500] 'applied index is now lower than readState.Index' (duration: 112.292146ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:29.631676Z","caller":"traceutil/trace.go:171","msg":"trace[1633631197] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"594.489305ms","start":"2026-04-08T00:34:29.037162Z","end":"2026-04-08T00:34:29.631651Z","steps":["trace[1633631197] 'process raft request' (duration: 482.087546ms)","trace[1633631197] 'compare' (duration: 111.326542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:29.631837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:29.037141Z","time spent":"594.592677ms","remote":"127.0.0.1:44938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:34:29.631876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.974443ms","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-04-08T00:34:29.631991Z","caller":"traceutil/trace.go:171","msg":"trace[1438532402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"537.140727ms","start":"2026-04-08T00:34:29.094827Z","end":"2026-04-08T00:34:29.631967Z","steps":["trace[1438532402] 'agreement among raft nodes before linearized reading' (duration: 536.887151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.632078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:29.094806Z","time spent":"537.258229ms","remote":"127.0.0.1:45004","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-04-08T00:34:29.640567Z","caller":"traceutil/trace.go:171","msg":"trace[2075591118] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"222.072511ms","start":"2026-04-08T00:34:29.418471Z","end":"2026-04-08T00:34:29.640544Z","steps":["trace[2075591118] 'process raft request' (duration: 221.116467ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:45.072239Z","caller":"traceutil/trace.go:171","msg":"trace[64478619] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"111.45941ms","start":"2026-04-08T00:34:44.960752Z","end":"2026-04-08T00:34:45.072212Z","steps":["trace[64478619] 'read index received' (duration: 98.701779ms)","trace[64478619] 'applied index is now lower than readState.Index' (duration: 12.756421ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:45.07241Z","caller":"traceutil/trace.go:171","msg":"trace[1525769028] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"152.337574ms","start":"2026-04-08T00:34:44.920042Z","end":"2026-04-08T00:34:45.07238Z","steps":["trace[1525769028] 'process raft request' (duration: 139.442861ms)","trace[1525769028] 'compare' (duration: 12.613377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:45.072586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.804087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-trt5f\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-08T00:34:45.072676Z","caller":"traceutil/trace.go:171","msg":"trace[1341311351] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-trt5f; range_end:; response_count:1; response_revision:782; }","duration":"111.955642ms","start":"2026-04-08T00:34:44.960699Z","end":"2026-04-08T00:34:45.072654Z","steps":["trace[1341311351] 'agreement among raft nodes before linearized reading' (duration: 111.704425ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:45.39448Z","caller":"traceutil/trace.go:171","msg":"trace[1410701925] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"313.816006ms","start":"2026-04-08T00:34:45.080632Z","end":"2026-04-08T00:34:45.394448Z","steps":["trace[1410701925] 'process raft request' (duration: 313.620602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:45.394712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:45.080604Z","time spent":"313.989049ms","remote":"127.0.0.1:44938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:34:45.429819Z","caller":"traceutil/trace.go:171","msg":"trace[1802358262] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:811; }","duration":"275.066631ms","start":"2026-04-08T00:34:45.154725Z","end":"2026-04-08T00:34:45.429791Z","steps":["trace[1802358262] 'read index received' (duration: 239.575404ms)","trace[1802358262] 'applied index is now lower than readState.Index' (duration: 35.489687ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:45.429891Z","caller":"traceutil/trace.go:171","msg":"trace[567051240] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"332.152958ms","start":"2026-04-08T00:34:45.097683Z","end":"2026-04-08T00:34:45.429836Z","steps":["trace[567051240] 'process raft request' (duration: 331.932823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:45.430181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.327277ms","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":"warn","ts":"2026-04-08T00:34:45.430198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.4552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T00:34:45.430242Z","caller":"traceutil/trace.go:171","msg":"trace[636463469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"111.46502ms","start":"2026-04-08T00:34:45.318765Z","end":"2026-04-08T00:34:45.43023Z","steps":["trace[636463469] 'agreement among raft nodes before linearized reading' (duration: 111.193244ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:45.430263Z","caller":"traceutil/trace.go:171","msg":"trace[1144258806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"275.558253ms","start":"2026-04-08T00:34:45.154686Z","end":"2026-04-08T00:34:45.430244Z","steps":["trace[1144258806] 'agreement among raft nodes before linearized reading' (duration: 275.236925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:45.430375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:45.097657Z","time spent":"332.314532ms","remote":"127.0.0.1:44774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:36:01.017498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.376704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:36:01.017591Z","caller":"traceutil/trace.go:171","msg":"trace[262562738] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1843; }","duration":"251.528837ms","start":"2026-04-08T00:36:00.766046Z","end":"2026-04-08T00:36:01.017574Z","steps":["trace[262562738] 'count revisions from in-memory index tree' (duration: 251.277822ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:01.502123Z","caller":"traceutil/trace.go:171","msg":"trace[1588890616] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"136.806056ms","start":"2026-04-08T00:36:01.365287Z","end":"2026-04-08T00:36:01.502093Z","steps":["trace[1588890616] 'read index received' (duration: 136.583381ms)","trace[1588890616] 'applied index is now lower than readState.Index' (duration: 221.515µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:01.5022Z","caller":"traceutil/trace.go:171","msg":"trace[824158525] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"166.994831ms","start":"2026-04-08T00:36:01.335171Z","end":"2026-04-08T00:36:01.502166Z","steps":["trace[824158525] 'process raft request' (duration: 166.765876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:01.502288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.979501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b16b21bf-2cb3-4b51-8a55-bef954e17b43\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-08T00:36:01.502375Z","caller":"traceutil/trace.go:171","msg":"trace[457676489] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b16b21bf-2cb3-4b51-8a55-bef954e17b43; range_end:; response_count:1; response_revision:1844; }","duration":"137.105633ms","start":"2026-04-08T00:36:01.365251Z","end":"2026-04-08T00:36:01.502357Z","steps":["trace[457676489] 'agreement among raft nodes before linearized reading' (duration: 136.93899ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:40.786188Z","caller":"traceutil/trace.go:171","msg":"trace[1551947651] linearizableReadLoop","detail":"{readStateIndex:2714; appliedIndex:2713; }","duration":"149.645733ms","start":"2026-04-08T00:37:40.636514Z","end":"2026-04-08T00:37:40.78616Z","steps":["trace[1551947651] 'read index received' (duration: 149.424887ms)","trace[1551947651] 'applied index is now lower than readState.Index' (duration: 219.706µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:40.786359Z","caller":"traceutil/trace.go:171","msg":"trace[651323417] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"162.999374ms","start":"2026-04-08T00:37:40.623324Z","end":"2026-04-08T00:37:40.786323Z","steps":["trace[651323417] 'process raft request' (duration: 162.701546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:40.786475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.940609ms","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-04-08T00:37:40.786545Z","caller":"traceutil/trace.go:171","msg":"trace[1184991581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2612; }","duration":"150.059662ms","start":"2026-04-08T00:37:40.636467Z","end":"2026-04-08T00:37:40.786526Z","steps":["trace[1184991581] 'agreement among raft nodes before linearized reading' (duration: 149.841517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:40.7869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.414867ms","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-04-08T00:37:40.786964Z","caller":"traceutil/trace.go:171","msg":"trace[634268418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2612; }","duration":"106.524879ms","start":"2026-04-08T00:37:40.680421Z","end":"2026-04-08T00:37:40.786946Z","steps":["trace[634268418] 'agreement among raft nodes before linearized reading' (duration: 105.973526ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:14.639642Z","caller":"traceutil/trace.go:171","msg":"trace[869271784] linearizableReadLoop","detail":"{readStateIndex:2923; appliedIndex:2922; }","duration":"104.938723ms","start":"2026-04-08T00:38:14.53468Z","end":"2026-04-08T00:38:14.639619Z","steps":["trace[869271784] 'read index received' (duration: 104.83527ms)","trace[869271784] 'applied index is now lower than readState.Index' (duration: 100.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:14.63975Z","caller":"traceutil/trace.go:171","msg":"trace[1957966889] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"109.173703ms","start":"2026-04-08T00:38:14.530552Z","end":"2026-04-08T00:38:14.639725Z","steps":["trace[1957966889] 'process raft request' (duration: 108.923877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:14.639953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.24796ms","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-04-08T00:38:14.640046Z","caller":"traceutil/trace.go:171","msg":"trace[550100572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2814; }","duration":"105.379823ms","start":"2026-04-08T00:38:14.534646Z","end":"2026-04-08T00:38:14.640025Z","steps":["trace[550100572] 'agreement among raft nodes before linearized reading' (duration: 105.148428ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:14.640916Z","caller":"traceutil/trace.go:171","msg":"trace[856371860] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"103.293415ms","start":"2026-04-08T00:38:14.537607Z","end":"2026-04-08T00:38:14.640901Z","steps":["trace[856371860] 'process raft request' (duration: 103.053018ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:40:39.776471Z","caller":"traceutil/trace.go:171","msg":"trace[535159833] transaction","detail":"{read_only:false; response_revision:3781; number_of_response:1; }","duration":"108.795809ms","start":"2026-04-08T00:40:39.66764Z","end":"2026-04-08T00:40:39.776436Z","steps":["trace[535159833] 'process raft request' (duration: 108.608254ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:06.363301Z","caller":"traceutil/trace.go:171","msg":"trace[232960193] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5172; }","duration":"124.72529ms","start":"2026-04-08T00:42:06.238546Z","end":"2026-04-08T00:42:06.363272Z","steps":["trace[232960193] 'read index received' (duration: 95.626232ms)","trace[232960193] 'applied index is now lower than readState.Index' (duration: 29.097218ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:06.363341Z","caller":"traceutil/trace.go:171","msg":"trace[1542550405] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"139.565111ms","start":"2026-04-08T00:42:06.223737Z","end":"2026-04-08T00:42:06.363302Z","steps":["trace[1542550405] 'process raft request' (duration: 110.555775ms)","trace[1542550405] 'compare' (duration: 28.582166ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:06.36357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.995377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:42:06.363675Z","caller":"traceutil/trace.go:171","msg":"trace[178515871] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4982; }","duration":"125.182891ms","start":"2026-04-08T00:42:06.238471Z","end":"2026-04-08T00:42:06.363654Z","steps":["trace[178515871] 'agreement among raft nodes before linearized reading' (duration: 124.923185ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:06.508824Z","caller":"traceutil/trace.go:171","msg":"trace[1139011720] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"140.610945ms","start":"2026-04-08T00:42:06.368186Z","end":"2026-04-08T00:42:06.508797Z","steps":["trace[1139011720] 'process raft request' (duration: 140.450401ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:06.51673Z","caller":"traceutil/trace.go:171","msg":"trace[529142247] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"147.695893ms","start":"2026-04-08T00:42:06.369004Z","end":"2026-04-08T00:42:06.5167Z","steps":["trace[529142247] 'process raft request' (duration: 147.652332ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:06.516797Z","caller":"traceutil/trace.go:171","msg":"trace[1458399679] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"148.221665ms","start":"2026-04-08T00:42:06.368536Z","end":"2026-04-08T00:42:06.516758Z","steps":["trace[1458399679] 'process raft request' (duration: 147.942109ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:24.395792Z","caller":"traceutil/trace.go:171","msg":"trace[1304700757] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"141.809416ms","start":"2026-04-08T00:42:24.253938Z","end":"2026-04-08T00:42:24.395748Z","steps":["trace[1304700757] 'process raft request' (duration: 141.5519ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:52.163776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3023} {"level":"info","ts":"2026-04-08T00:43:52.246374Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3023,"took":"81.137796ms","hash":1304385836,"current-db-size-bytes":58036224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31531008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-08T00:43:52.246441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1304385836,"revision":3023,"compact-revision":-1} {"level":"info","ts":"2026-04-08T00:48:16.093459Z","caller":"traceutil/trace.go:171","msg":"trace[1670853108] linearizableReadLoop","detail":"{readStateIndex:8396; appliedIndex:8395; }","duration":"324.996533ms","start":"2026-04-08T00:48:15.768436Z","end":"2026-04-08T00:48:16.093432Z","steps":["trace[1670853108] 'read index received' (duration: 324.803199ms)","trace[1670853108] 'applied index is now lower than readState.Index' (duration: 192.024µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:16.09357Z","caller":"traceutil/trace.go:171","msg":"trace[851989108] transaction","detail":"{read_only:false; response_revision:8109; number_of_response:1; }","duration":"338.812311ms","start":"2026-04-08T00:48:15.754723Z","end":"2026-04-08T00:48:16.093535Z","steps":["trace[851989108] 'process raft request' (duration: 338.527234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:16.093713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.263909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bwgph\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-08T00:48:16.093755Z","caller":"traceutil/trace.go:171","msg":"trace[1641828256] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bwgph; range_end:; response_count:1; response_revision:8109; }","duration":"325.367262ms","start":"2026-04-08T00:48:15.768377Z","end":"2026-04-08T00:48:16.093744Z","steps":["trace[1641828256] 'agreement among raft nodes before linearized reading' (duration: 325.209298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:16.093753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:15.754683Z","time spent":"338.977665ms","remote":"127.0.0.1:45004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:48:16.093801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:15.768354Z","time spent":"325.436424ms","remote":"127.0.0.1:44938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-bwgph\" "} {"level":"warn","ts":"2026-04-08T00:48:16.09384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.159913ms","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-04-08T00:48:16.093908Z","caller":"traceutil/trace.go:171","msg":"trace[648125752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8109; }","duration":"281.288256ms","start":"2026-04-08T00:48:15.812604Z","end":"2026-04-08T00:48:16.093892Z","steps":["trace[648125752] 'agreement among raft nodes before linearized reading' (duration: 281.082601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:16.093955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.815349ms","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-04-08T00:48:16.094038Z","caller":"traceutil/trace.go:171","msg":"trace[1749370992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8109; }","duration":"171.947171ms","start":"2026-04-08T00:48:15.922074Z","end":"2026-04-08T00:48:16.094021Z","steps":["trace[1749370992] 'agreement among raft nodes before linearized reading' (duration: 171.713846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:28.918776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.237192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-08T00:48:28.918914Z","caller":"traceutil/trace.go:171","msg":"trace[1892799673] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8177; }","duration":"133.412106ms","start":"2026-04-08T00:48:28.785473Z","end":"2026-04-08T00:48:28.918885Z","steps":["trace[1892799673] 'range keys from in-memory index tree' (duration: 133.059858ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:52.171775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6135} {"level":"info","ts":"2026-04-08T00:48:52.266614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6135,"took":"91.721147ms","hash":1069281639,"current-db-size-bytes":58036224,"current-db-size":"58 MB","current-db-size-in-use-bytes":23052288,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-08T00:48:52.266674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1069281639,"revision":6135,"compact-revision":3023} {"level":"warn","ts":"2026-04-08T00:49:10.472894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.872433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:49:10.473149Z","caller":"traceutil/trace.go:171","msg":"trace[784912962] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"463.765403ms","start":"2026-04-08T00:49:10.009303Z","end":"2026-04-08T00:49:10.473069Z","steps":["trace[784912962] 'process raft request' (duration: 207.631704ms)","trace[784912962] 'compare' (duration: 255.339791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:49:10.473382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:10.009291Z","time spent":"463.978579ms","remote":"127.0.0.1:44938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4625,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:49:10.473467Z","caller":"traceutil/trace.go:171","msg":"trace[1750082520] linearizableReadLoop","detail":"{readStateIndex:8793; appliedIndex:8792; }","duration":"463.076088ms","start":"2026-04-08T00:49:10.010358Z","end":"2026-04-08T00:49:10.473434Z","steps":["trace[1750082520] 'read index received' (duration: 206.480318ms)","trace[1750082520] 'applied index is now lower than readState.Index' (duration: 256.593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:49:10.473609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.082387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"info","ts":"2026-04-08T00:49:10.473658Z","caller":"traceutil/trace.go:171","msg":"trace[940489173] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8490; }","duration":"468.145789ms","start":"2026-04-08T00:49:10.0055Z","end":"2026-04-08T00:49:10.473645Z","steps":["trace[940489173] 'agreement among raft nodes before linearized reading' (duration: 468.004694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:10.473692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:10.005491Z","time spent":"468.19468ms","remote":"127.0.0.1:44982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":3089,"request content":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" "} {"level":"info","ts":"2026-04-08T00:49:10.473813Z","caller":"traceutil/trace.go:171","msg":"trace[1020737616] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"411.782266ms","start":"2026-04-08T00:49:10.062016Z","end":"2026-04-08T00:49:10.473798Z","steps":["trace[1020737616] 'process raft request' (duration: 411.387676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:10.473978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:10.061988Z","time spent":"411.865078ms","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:49:10.474397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.74151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-04-08T00:49:10.474414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.59429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-04-08T00:49:10.474438Z","caller":"traceutil/trace.go:171","msg":"trace[462933989] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8492; }","duration":"451.812101ms","start":"2026-04-08T00:49:10.022616Z","end":"2026-04-08T00:49:10.474428Z","steps":["trace[462933989] 'agreement among raft nodes before linearized reading' (duration: 451.682368ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:10.474449Z","caller":"traceutil/trace.go:171","msg":"trace[1353315369] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8492; }","duration":"267.665092ms","start":"2026-04-08T00:49:10.206772Z","end":"2026-04-08T00:49:10.474437Z","steps":["trace[1353315369] 'agreement among raft nodes before linearized reading' (duration: 267.545029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:10.47446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:10.0226Z","time spent":"451.854192ms","remote":"127.0.0.1:44852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":554,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-04-08T00:49:10.474588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.823039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:49:10.474613Z","caller":"traceutil/trace.go:171","msg":"trace[830468549] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8492; }","duration":"442.87211ms","start":"2026-04-08T00:49:10.031735Z","end":"2026-04-08T00:49:10.474607Z","steps":["trace[830468549] 'agreement among raft nodes before linearized reading' (duration: 442.823659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:10.474631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:10.031724Z","time spent":"442.901411ms","remote":"127.0.0.1:44900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":7,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-04-08T00:49:10.474713Z","caller":"traceutil/trace.go:171","msg":"trace[680855758] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"158.291439ms","start":"2026-04-08T00:49:10.316409Z","end":"2026-04-08T00:49:10.474701Z","steps":["trace[680855758] 'process raft request' (duration: 157.819358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:10.474754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.875642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-mon\" ","response":"range_response_count:1 size:713"} {"level":"info","ts":"2026-04-08T00:49:10.474774Z","caller":"traceutil/trace.go:171","msg":"trace[1535693651] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-mon; range_end:; response_count:1; response_revision:8492; }","duration":"451.911174ms","start":"2026-04-08T00:49:10.022856Z","end":"2026-04-08T00:49:10.474767Z","steps":["trace[1535693651] 'agreement among raft nodes before linearized reading' (duration: 451.868523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:10.474797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:10.022851Z","time spent":"451.942574ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":736,"request content":"key:\"/registry/secrets/openstack/rook-ceph-mon\" "} {"level":"warn","ts":"2026-04-08T00:49:10.474923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.159359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"info","ts":"2026-04-08T00:49:10.474947Z","caller":"traceutil/trace.go:171","msg":"trace[281137985] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8492; }","duration":"452.19727ms","start":"2026-04-08T00:49:10.022744Z","end":"2026-04-08T00:49:10.474941Z","steps":["trace[281137985] 'agreement among raft nodes before linearized reading' (duration: 452.135548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:10.474983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:10.022738Z","time spent":"452.238031ms","remote":"127.0.0.1:44982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":3089,"request content":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" "} {"level":"info","ts":"2026-04-08T00:49:11.438504Z","caller":"traceutil/trace.go:171","msg":"trace[2022711485] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"111.713239ms","start":"2026-04-08T00:49:11.326764Z","end":"2026-04-08T00:49:11.438478Z","steps":["trace[2022711485] 'process raft request' (duration: 111.546965ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:11.47212Z","caller":"traceutil/trace.go:171","msg":"trace[1451990582] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"122.419832ms","start":"2026-04-08T00:49:11.349679Z","end":"2026-04-08T00:49:11.472099Z","steps":["trace[1451990582] 'process raft request' (duration: 122.304409ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:12.78827Z","caller":"traceutil/trace.go:171","msg":"trace[226843850] transaction","detail":"{read_only:false; response_revision:8513; number_of_response:1; }","duration":"170.910657ms","start":"2026-04-08T00:49:12.617336Z","end":"2026-04-08T00:49:12.788247Z","steps":["trace[226843850] 'process raft request' (duration: 170.730223ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:12.830726Z","caller":"traceutil/trace.go:171","msg":"trace[419963868] transaction","detail":"{read_only:false; response_revision:8514; number_of_response:1; }","duration":"213.218366ms","start":"2026-04-08T00:49:12.617458Z","end":"2026-04-08T00:49:12.830676Z","steps":["trace[419963868] 'process raft request' (duration: 212.970601ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:10.119559Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024e8.wal"} {"level":"info","ts":"2026-04-08T00:50:23.378241Z","caller":"traceutil/trace.go:171","msg":"trace[1290488536] transaction","detail":"{read_only:false; response_revision:9224; number_of_response:1; }","duration":"172.245568ms","start":"2026-04-08T00:50:23.20596Z","end":"2026-04-08T00:50:23.378205Z","steps":["trace[1290488536] 'process raft request' (duration: 111.208756ms)","trace[1290488536] 'compare' (duration: 60.867358ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:50:23.378275Z","caller":"traceutil/trace.go:171","msg":"trace[20424595] linearizableReadLoop","detail":"{readStateIndex:9547; appliedIndex:9546; }","duration":"170.549909ms","start":"2026-04-08T00:50:23.20769Z","end":"2026-04-08T00:50:23.37824Z","steps":["trace[20424595] 'read index received' (duration: 109.446235ms)","trace[20424595] 'applied index is now lower than readState.Index' (duration: 61.102624ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:50:23.378422Z","caller":"traceutil/trace.go:171","msg":"trace[916170030] transaction","detail":"{read_only:false; response_revision:9225; number_of_response:1; }","duration":"170.494397ms","start":"2026-04-08T00:50:23.207888Z","end":"2026-04-08T00:50:23.378383Z","steps":["trace[916170030] 'process raft request' (duration: 170.309103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:23.37856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.898598ms","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-04-08T00:50:23.378563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.855056ms","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-04-08T00:50:23.378608Z","caller":"traceutil/trace.go:171","msg":"trace[1061198235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9225; }","duration":"133.017061ms","start":"2026-04-08T00:50:23.24558Z","end":"2026-04-08T00:50:23.378597Z","steps":["trace[1061198235] 'agreement among raft nodes before linearized reading' (duration: 132.839857ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:23.37863Z","caller":"traceutil/trace.go:171","msg":"trace[857259876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9225; }","duration":"170.980888ms","start":"2026-04-08T00:50:23.20763Z","end":"2026-04-08T00:50:23.378611Z","steps":["trace[857259876] 'agreement among raft nodes before linearized reading' (duration: 170.764433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:23.378785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.599648ms","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-04-08T00:50:23.378833Z","caller":"traceutil/trace.go:171","msg":"trace[1004262122] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9225; }","duration":"128.685369ms","start":"2026-04-08T00:50:23.250133Z","end":"2026-04-08T00:50:23.378819Z","steps":["trace[1004262122] 'agreement among raft nodes before linearized reading' (duration: 128.494365ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:51:24.001327Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T00:51:24.008958Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T00:51:24.009076Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T00:53:52.179417Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8309} {"level":"info","ts":"2026-04-08T00:53:52.259955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8309,"took":"77.984686ms","hash":3777427598,"current-db-size-bytes":58036224,"current-db-size":"58 MB","current-db-size-in-use-bytes":24756224,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-08T00:53:52.260019Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3777427598,"revision":8309,"compact-revision":6135} {"level":"info","ts":"2026-04-08T00:58:52.186634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10700} {"level":"info","ts":"2026-04-08T00:58:52.269102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10700,"took":"80.190676ms","hash":1087595461,"current-db-size-bytes":58036224,"current-db-size":"58 MB","current-db-size-in-use-bytes":20123648,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-08T00:58:52.269164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1087595461,"revision":10700,"compact-revision":8309}