{"level":"warn","ts":"2026-06-13T22:39:43.590534Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T22:39:43.590606Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-06-13T22:39:43.590657Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T22:39:43.590667Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-06-13T22:39:43.590684Z","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-06-13T22:39:43.591191Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-06-13T22:39:43.591262Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-06-13T22:39:43.595634Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.828819ms"} {"level":"info","ts":"2026-06-13T22:39:43.602554Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-06-13T22:39:43.602593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-06-13T22:39:43.602606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-06-13T22:39:43.602616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-13T22:39:43.602625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-06-13T22:39:43.602655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-06-13T22:39:43.610278Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-13T22:39:43.613512Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-13T22:39:43.614734Z","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-06-13T22:39:43.616839Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-13T22:39:43.616929Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-13T22:39:43.617006Z","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-06-13T22:39:43.617047Z","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-06-13T22:39:43.617056Z","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-06-13T22:39:43.619305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-06-13T22:39:43.619435Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-06-13T22:39:43.622501Z","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-06-13T22:39:43.622614Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-06-13T22:39:43.622641Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-06-13T22:39:43.622754Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-13T22:39:43.622804Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-13T22:39:43.703565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-06-13T22:39:43.703644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-06-13T22:39:43.703677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-06-13T22:39:43.703699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-06-13T22:39:43.703716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-06-13T22:39:43.703733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-06-13T22:39:43.703751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-06-13T22:39:43.704699Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-06-13T22:39:43.704931Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:39:43.705903Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:39:43.706263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:39:43.706517Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-13T22:39:43.706535Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-13T22:39:43.706578Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:39:43.706688Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:39:43.706711Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:39:43.708651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-06-13T22:39:43.709078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-13T22:40:18.504484Z","caller":"traceutil/trace.go:171","msg":"trace[733672453] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"174.642044ms","start":"2026-06-13T22:40:18.329804Z","end":"2026-06-13T22:40:18.504446Z","steps":["trace[733672453] 'process raft request' (duration: 81.185951ms)","trace[733672453] 'compare' (duration: 93.10983ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:40:18.504982Z","caller":"traceutil/trace.go:171","msg":"trace[1285542755] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"175.059048ms","start":"2026-06-13T22:40:18.329912Z","end":"2026-06-13T22:40:18.504971Z","steps":["trace[1285542755] 'process raft request' (duration: 174.483953ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:40:18.505276Z","caller":"traceutil/trace.go:171","msg":"trace[96641909] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"164.995911ms","start":"2026-06-13T22:40:18.340247Z","end":"2026-06-13T22:40:18.505243Z","steps":["trace[96641909] 'process raft request' (duration: 164.566306ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:40:18.505387Z","caller":"traceutil/trace.go:171","msg":"trace[222416808] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"114.063054ms","start":"2026-06-13T22:40:18.391296Z","end":"2026-06-13T22:40:18.505359Z","steps":["trace[222416808] 'process raft request' (duration: 113.809561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:40:24.396805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.359377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:40:24.397173Z","caller":"traceutil/trace.go:171","msg":"trace[1264093954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"130.77136ms","start":"2026-06-13T22:40:24.266299Z","end":"2026-06-13T22:40:24.397071Z","steps":["trace[1264093954] 'range keys from in-memory index tree' (duration: 130.238335ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:40:24.820148Z","caller":"traceutil/trace.go:171","msg":"trace[636149366] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"233.41218ms","start":"2026-06-13T22:40:24.586705Z","end":"2026-06-13T22:40:24.820117Z","steps":["trace[636149366] 'process raft request' (duration: 232.720564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:40:24.820314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.115832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6qkrz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-13T22:40:24.820406Z","caller":"traceutil/trace.go:171","msg":"trace[2121436492] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6qkrz; range_end:; response_count:1; response_revision:591; }","duration":"227.259473ms","start":"2026-06-13T22:40:24.593132Z","end":"2026-06-13T22:40:24.820391Z","steps":["trace[2121436492] 'agreement among raft nodes before linearized reading' (duration: 227.064771ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:40:24.820148Z","caller":"traceutil/trace.go:171","msg":"trace[2047224951] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"226.89ms","start":"2026-06-13T22:40:24.593182Z","end":"2026-06-13T22:40:24.820072Z","steps":["trace[2047224951] 'read index received' (duration: 226.057193ms)","trace[2047224951] 'applied index is now lower than readState.Index' (duration: 831.687µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:40:25.424041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.574736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:40:25.424196Z","caller":"traceutil/trace.go:171","msg":"trace[403754703] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"543.255767ms","start":"2026-06-13T22:40:24.880915Z","end":"2026-06-13T22:40:25.424171Z","steps":["trace[403754703] 'process raft request' (duration: 45.3634ms)","trace[403754703] 'compare' (duration: 496.448554ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:40:25.424359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:40:24.880901Z","time spent":"543.384698ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:40:25.424466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.691806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:40:25.424153Z","caller":"traceutil/trace.go:171","msg":"trace[762299988] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"159.363463ms","start":"2026-06-13T22:40:25.264772Z","end":"2026-06-13T22:40:25.424136Z","steps":["trace[762299988] 'read index received' (duration: 58.23µs)","trace[762299988] 'applied index is now lower than readState.Index' (duration: 159.303593ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:40:25.424554Z","caller":"traceutil/trace.go:171","msg":"trace[1127396245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"159.800508ms","start":"2026-06-13T22:40:25.264728Z","end":"2026-06-13T22:40:25.424528Z","steps":["trace[1127396245] 'agreement among raft nodes before linearized reading' (duration: 159.625326ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:41:29.10932Z","caller":"traceutil/trace.go:171","msg":"trace[755794944] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"113.229261ms","start":"2026-06-13T22:41:28.996058Z","end":"2026-06-13T22:41:29.109287Z","steps":["trace[755794944] 'read index received' (duration: 97.206224ms)","trace[755794944] 'applied index is now lower than readState.Index' (duration: 16.021727ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:41:29.109749Z","caller":"traceutil/trace.go:171","msg":"trace[333103580] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"125.453648ms","start":"2026-06-13T22:41:28.984273Z","end":"2026-06-13T22:41:29.109727Z","steps":["trace[333103580] 'process raft request' (duration: 108.975735ms)","trace[333103580] 'compare' (duration: 15.885772ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:41:29.110153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.08181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-13T22:41:29.110214Z","caller":"traceutil/trace.go:171","msg":"trace[150626601] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1408; }","duration":"114.187553ms","start":"2026-06-13T22:41:28.996008Z","end":"2026-06-13T22:41:29.110196Z","steps":["trace[150626601] 'agreement among raft nodes before linearized reading' (duration: 114.012827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:41:29.110438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.868566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:41:29.110486Z","caller":"traceutil/trace.go:171","msg":"trace[1054345804] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1408; }","duration":"111.922448ms","start":"2026-06-13T22:41:28.998551Z","end":"2026-06-13T22:41:29.110474Z","steps":["trace[1054345804] 'agreement among raft nodes before linearized reading' (duration: 111.842755ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:41:53.25217Z","caller":"traceutil/trace.go:171","msg":"trace[1887953235] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1897; }","duration":"207.110486ms","start":"2026-06-13T22:41:53.045032Z","end":"2026-06-13T22:41:53.252142Z","steps":["trace[1887953235] 'read index received' (duration: 206.950842ms)","trace[1887953235] 'applied index is now lower than readState.Index' (duration: 158.404µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:41:53.25235Z","caller":"traceutil/trace.go:171","msg":"trace[1778057663] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"214.196432ms","start":"2026-06-13T22:41:53.038083Z","end":"2026-06-13T22:41:53.252279Z","steps":["trace[1778057663] 'process raft request' (duration: 213.884334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:41:53.252494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.357033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:41:53.252575Z","caller":"traceutil/trace.go:171","msg":"trace[1552896572] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1828; }","duration":"163.471206ms","start":"2026-06-13T22:41:53.089087Z","end":"2026-06-13T22:41:53.252558Z","steps":["trace[1552896572] 'agreement among raft nodes before linearized reading' (duration: 163.301982ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:41:53.252469Z","caller":"traceutil/trace.go:171","msg":"trace[1433525971] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"165.904669ms","start":"2026-06-13T22:41:53.086533Z","end":"2026-06-13T22:41:53.252438Z","steps":["trace[1433525971] 'process raft request' (duration: 165.773796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:41:53.252624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.300895ms","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-06-13T22:41:53.252681Z","caller":"traceutil/trace.go:171","msg":"trace[1666686416] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1828; }","duration":"131.371646ms","start":"2026-06-13T22:41:53.121295Z","end":"2026-06-13T22:41:53.252666Z","steps":["trace[1666686416] 'agreement among raft nodes before linearized reading' (duration: 131.170001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:41:53.252841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.892682ms","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-06-13T22:41:53.252981Z","caller":"traceutil/trace.go:171","msg":"trace[2119433764] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1828; }","duration":"139.060377ms","start":"2026-06-13T22:41:53.113908Z","end":"2026-06-13T22:41:53.252968Z","steps":["trace[2119433764] 'agreement among raft nodes before linearized reading' (duration: 138.864802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:41:53.253133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.066152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-13T22:41:53.253226Z","caller":"traceutil/trace.go:171","msg":"trace[2016459248] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1828; }","duration":"208.217305ms","start":"2026-06-13T22:41:53.044989Z","end":"2026-06-13T22:41:53.253206Z","steps":["trace[2016459248] 'agreement among raft nodes before linearized reading' (duration: 207.314092ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:42:08.749339Z","caller":"traceutil/trace.go:171","msg":"trace[1882447986] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"308.04567ms","start":"2026-06-13T22:42:08.441268Z","end":"2026-06-13T22:42:08.749313Z","steps":["trace[1882447986] 'process raft request' (duration: 307.874826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:42:08.749609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:42:08.441252Z","time spent":"308.161912ms","remote":"127.0.0.1:59098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:42:08.807782Z","caller":"traceutil/trace.go:171","msg":"trace[291208654] linearizableReadLoop","detail":"{readStateIndex:2025; appliedIndex:2023; }","duration":"162.401082ms","start":"2026-06-13T22:42:08.645359Z","end":"2026-06-13T22:42:08.80776Z","steps":["trace[291208654] 'read index received' (duration: 103.788122ms)","trace[291208654] 'applied index is now lower than readState.Index' (duration: 58.61198ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:42:08.807979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.605918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-qgpln\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-06-13T22:42:08.808021Z","caller":"traceutil/trace.go:171","msg":"trace[89862784] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-qgpln; range_end:; response_count:1; response_revision:1950; }","duration":"162.685369ms","start":"2026-06-13T22:42:08.645322Z","end":"2026-06-13T22:42:08.808007Z","steps":["trace[89862784] 'agreement among raft nodes before linearized reading' (duration: 162.532576ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:42:08.808024Z","caller":"traceutil/trace.go:171","msg":"trace[1599534900] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"363.683292ms","start":"2026-06-13T22:42:08.444322Z","end":"2026-06-13T22:42:08.808005Z","steps":["trace[1599534900] 'process raft request' (duration: 363.316483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:42:08.808144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:42:08.444313Z","time spent":"363.753394ms","remote":"127.0.0.1:59098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:42:32.371949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.460344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:42:32.372042Z","caller":"traceutil/trace.go:171","msg":"trace[2107968607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2092; }","duration":"106.600666ms","start":"2026-06-13T22:42:32.265422Z","end":"2026-06-13T22:42:32.372022Z","steps":["trace[2107968607] 'range keys from in-memory index tree' (duration: 106.375451ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:42:32.467655Z","caller":"traceutil/trace.go:171","msg":"trace[971730901] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"179.887856ms","start":"2026-06-13T22:42:32.28775Z","end":"2026-06-13T22:42:32.467638Z","steps":["trace[971730901] 'process raft request' (duration: 179.756234ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:42:33.005366Z","caller":"traceutil/trace.go:171","msg":"trace[825446786] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"111.4536ms","start":"2026-06-13T22:42:32.893885Z","end":"2026-06-13T22:42:33.005339Z","steps":["trace[825446786] 'process raft request' (duration: 111.126533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:42:33.247961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.271296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:42:33.249424Z","caller":"traceutil/trace.go:171","msg":"trace[1872940351] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"238.277228ms","start":"2026-06-13T22:42:33.00977Z","end":"2026-06-13T22:42:33.248047Z","steps":["trace[1872940351] 'process raft request' (duration: 91.790857ms)","trace[1872940351] 'compare' (duration: 145.891359ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:43:56.456006Z","caller":"traceutil/trace.go:171","msg":"trace[1114316990] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"159.919282ms","start":"2026-06-13T22:43:56.296055Z","end":"2026-06-13T22:43:56.455975Z","steps":["trace[1114316990] 'process raft request' (duration: 79.319492ms)","trace[1114316990] 'compare' (duration: 80.088422ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:43:56.712884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.704148ms","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-06-13T22:43:56.712992Z","caller":"traceutil/trace.go:171","msg":"trace[1037058351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2656; }","duration":"139.82143ms","start":"2026-06-13T22:43:56.573144Z","end":"2026-06-13T22:43:56.712966Z","steps":["trace[1037058351] 'range keys from in-memory index tree' (duration: 139.514395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:56.713503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.294384ms","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-06-13T22:43:56.713578Z","caller":"traceutil/trace.go:171","msg":"trace[985090120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2656; }","duration":"139.384885ms","start":"2026-06-13T22:43:56.574173Z","end":"2026-06-13T22:43:56.713558Z","steps":["trace[985090120] 'range keys from in-memory index tree' (duration: 139.146502ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:47:27.607637Z","caller":"traceutil/trace.go:171","msg":"trace[200739504] transaction","detail":"{read_only:false; response_revision:4296; number_of_response:1; }","duration":"102.069111ms","start":"2026-06-13T22:47:27.505537Z","end":"2026-06-13T22:47:27.607606Z","steps":["trace[200739504] 'process raft request' (duration: 90.196747ms)","trace[200739504] 'compare' (duration: 11.741871ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:47:35.913288Z","caller":"traceutil/trace.go:171","msg":"trace[2087463370] linearizableReadLoop","detail":"{readStateIndex:4691; appliedIndex:4690; }","duration":"126.564515ms","start":"2026-06-13T22:47:35.786702Z","end":"2026-06-13T22:47:35.913267Z","steps":["trace[2087463370] 'read index received' (duration: 126.417452ms)","trace[2087463370] 'applied index is now lower than readState.Index' (duration: 146.413µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:47:35.913321Z","caller":"traceutil/trace.go:171","msg":"trace[1715177109] transaction","detail":"{read_only:false; response_revision:4518; number_of_response:1; }","duration":"167.65107ms","start":"2026-06-13T22:47:35.745635Z","end":"2026-06-13T22:47:35.913286Z","steps":["trace[1715177109] 'process raft request' (duration: 167.523877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:35.913521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.234567ms","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-06-13T22:47:35.913549Z","caller":"traceutil/trace.go:171","msg":"trace[1981350467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4518; }","duration":"103.305289ms","start":"2026-06-13T22:47:35.810238Z","end":"2026-06-13T22:47:35.913543Z","steps":["trace[1981350467] 'agreement among raft nodes before linearized reading' (duration: 103.165106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:35.913589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.851811ms","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-06-13T22:47:35.913675Z","caller":"traceutil/trace.go:171","msg":"trace[640024000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4518; }","duration":"126.991635ms","start":"2026-06-13T22:47:35.786669Z","end":"2026-06-13T22:47:35.91366Z","steps":["trace[640024000] 'agreement among raft nodes before linearized reading' (duration: 126.711219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:40.504542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.046354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:47:40.504609Z","caller":"traceutil/trace.go:171","msg":"trace[1637912196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4600; }","duration":"238.157496ms","start":"2026-06-13T22:47:40.266438Z","end":"2026-06-13T22:47:40.504596Z","steps":["trace[1637912196] 'range keys from in-memory index tree' (duration: 237.956472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:40.504669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.616622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:47:40.5047Z","caller":"traceutil/trace.go:171","msg":"trace[944553169] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:4600; }","duration":"100.673662ms","start":"2026-06-13T22:47:40.404018Z","end":"2026-06-13T22:47:40.504692Z","steps":["trace[944553169] 'count revisions from in-memory index tree' (duration: 100.53122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:40.50483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.627763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-13T22:47:40.504951Z","caller":"traceutil/trace.go:171","msg":"trace[1235378175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4600; }","duration":"196.800117ms","start":"2026-06-13T22:47:40.308121Z","end":"2026-06-13T22:47:40.504921Z","steps":["trace[1235378175] 'range keys from in-memory index tree' (duration: 196.412309ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:47:40.864832Z","caller":"traceutil/trace.go:171","msg":"trace[950353895] transaction","detail":"{read_only:false; response_revision:4604; number_of_response:1; }","duration":"159.316052ms","start":"2026-06-13T22:47:40.70549Z","end":"2026-06-13T22:47:40.864806Z","steps":["trace[950353895] 'process raft request' (duration: 159.182799ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:47:40.875143Z","caller":"traceutil/trace.go:171","msg":"trace[2046784397] transaction","detail":"{read_only:false; response_revision:4605; number_of_response:1; }","duration":"100.356656ms","start":"2026-06-13T22:47:40.774769Z","end":"2026-06-13T22:47:40.875126Z","steps":["trace[2046784397] 'process raft request' (duration: 100.270684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:49.434042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.831227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:47:49.434134Z","caller":"traceutil/trace.go:171","msg":"trace[2041461759] linearizableReadLoop","detail":"{readStateIndex:4928; appliedIndex:4927; }","duration":"179.797694ms","start":"2026-06-13T22:47:49.254321Z","end":"2026-06-13T22:47:49.434119Z","steps":["trace[2041461759] 'read index received' (duration: 77.717742ms)","trace[2041461759] 'applied index is now lower than readState.Index' (duration: 102.079042ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:47:49.434161Z","caller":"traceutil/trace.go:171","msg":"trace[1704796728] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"185.771439ms","start":"2026-06-13T22:47:49.248358Z","end":"2026-06-13T22:47:49.434129Z","steps":["trace[1704796728] 'process raft request' (duration: 83.728208ms)","trace[1704796728] 'compare' (duration: 101.472429ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:47:49.434258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.930816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-13T22:47:49.434305Z","caller":"traceutil/trace.go:171","msg":"trace[1521731406] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4748; }","duration":"179.988448ms","start":"2026-06-13T22:47:49.254305Z","end":"2026-06-13T22:47:49.434294Z","steps":["trace[1521731406] 'agreement among raft nodes before linearized reading' (duration: 179.855996ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:47:54.46324Z","caller":"traceutil/trace.go:171","msg":"trace[348256721] linearizableReadLoop","detail":"{readStateIndex:4969; appliedIndex:4968; }","duration":"278.194508ms","start":"2026-06-13T22:47:54.185011Z","end":"2026-06-13T22:47:54.463205Z","steps":["trace[348256721] 'read index received' (duration: 193.37828ms)","trace[348256721] 'applied index is now lower than readState.Index' (duration: 84.815108ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:47:54.46357Z","caller":"traceutil/trace.go:171","msg":"trace[395019951] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"343.816819ms","start":"2026-06-13T22:47:54.11974Z","end":"2026-06-13T22:47:54.463557Z","steps":["trace[395019951] 'process raft request' (duration: 258.652093ms)","trace[395019951] 'compare' (duration: 84.651795ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:47:54.463683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.935208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:47:54.463717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:47:54.119718Z","time spent":"343.88995ms","remote":"127.0.0.1:59098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:47:54.463759Z","caller":"traceutil/trace.go:171","msg":"trace[1550838050] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4788; }","duration":"190.021089ms","start":"2026-06-13T22:47:54.273716Z","end":"2026-06-13T22:47:54.463737Z","steps":["trace[1550838050] 'agreement among raft nodes before linearized reading' (duration: 189.897597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:47:54.463908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.825441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-06-13T22:47:54.464158Z","caller":"traceutil/trace.go:171","msg":"trace[966579484] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4788; }","duration":"279.174178ms","start":"2026-06-13T22:47:54.184962Z","end":"2026-06-13T22:47:54.464136Z","steps":["trace[966579484] 'agreement among raft nodes before linearized reading' (duration: 278.690859ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:49:43.990526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2905} {"level":"info","ts":"2026-06-13T22:49:44.066631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2905,"took":"74.65199ms","hash":899123339,"current-db-size-bytes":53555200,"current-db-size":"54 MB","current-db-size-in-use-bytes":29569024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-13T22:49:44.066702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":899123339,"revision":2905,"compact-revision":-1} {"level":"info","ts":"2026-06-13T22:54:43.996444Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5627} {"level":"info","ts":"2026-06-13T22:54:44.064534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5627,"took":"66.881157ms","hash":1028364560,"current-db-size-bytes":53555200,"current-db-size":"54 MB","current-db-size-in-use-bytes":18010112,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-13T22:54:44.064597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1028364560,"revision":5627,"compact-revision":2905}