{"level":"warn","ts":"2026-08-04T15:15:47.631611Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T15:15:47.631692Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200: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.19.213.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-04T15:15:47.631766Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T15:15:47.63178Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-08-04T15:15:47.631797Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-04T15:15:47.632375Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-08-04T15:15:47.632442Z","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.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-04T15:15:47.636781Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.148157ms"} {"level":"info","ts":"2026-08-04T15:15:47.648015Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-08-04T15:15:47.648119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T15:15:47.648155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-08-04T15:15:47.648166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T15:15:47.648184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-08-04T15:15:47.648232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-08-04T15:15:47.654042Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T15:15:47.655401Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T15:15:47.656616Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-04T15:15:47.659545Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T15:15:47.660102Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T15:15:47.660279Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-04T15:15:47.660467Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-04T15:15:47.661086Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-04T15:15:47.66182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-08-04T15:15:47.662012Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-08-04T15:15:47.664866Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-04T15:15:47.665102Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-08-04T15:15:47.665134Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-08-04T15:15:47.66515Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T15:15:47.665207Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T15:15:47.848459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T15:15:47.848534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T15:15:47.848628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-08-04T15:15:47.848684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-08-04T15:15:47.848704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-08-04T15:15:47.84875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-08-04T15:15:47.848771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-08-04T15:15:47.849985Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T15:15:47.850159Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:15:47.850263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:15:47.853214Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-08-04T15:15:47.850093Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:15:47.854051Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T15:15:47.85408Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T15:15:47.854511Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:15:47.854636Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:15:47.854751Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:15:47.854821Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-04T15:16:30.522266Z","caller":"traceutil/trace.go:171","msg":"trace[1918094268] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"205.854663ms","start":"2026-08-04T15:16:30.316387Z","end":"2026-08-04T15:16:30.522242Z","steps":["trace[1918094268] 'process raft request' (duration: 205.321026ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:16:30.625938Z","caller":"traceutil/trace.go:171","msg":"trace[871533890] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"122.694421ms","start":"2026-08-04T15:16:30.503208Z","end":"2026-08-04T15:16:30.625902Z","steps":["trace[871533890] 'process raft request' (duration: 105.597681ms)","trace[871533890] 'compare' (duration: 16.888614ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:16:31.133906Z","caller":"traceutil/trace.go:171","msg":"trace[2013663135] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"407.992323ms","start":"2026-08-04T15:16:30.725873Z","end":"2026-08-04T15:16:31.133865Z","steps":["trace[2013663135] 'process raft request' (duration: 407.776717ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:16:31.134682Z","caller":"traceutil/trace.go:171","msg":"trace[452816420] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"305.126533ms","start":"2026-08-04T15:16:30.829477Z","end":"2026-08-04T15:16:31.134604Z","steps":["trace[452816420] 'read index received' (duration: 304.221516ms)","trace[452816420] 'applied index is now lower than readState.Index' (duration: 898.257µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:16:31.135084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.431832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-04T15:16:31.135155Z","caller":"traceutil/trace.go:171","msg":"trace[1721183150] range","detail":"{range_begin:/registry/masterleases/199.19.213.200; range_end:; response_count:1; response_revision:638; }","duration":"305.728901ms","start":"2026-08-04T15:16:30.829414Z","end":"2026-08-04T15:16:31.135143Z","steps":["trace[1721183150] 'agreement among raft nodes before linearized reading' (duration: 305.413351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:31.1352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:30.829392Z","time spent":"305.798493ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.200\" "} {"level":"warn","ts":"2026-08-04T15:16:31.136146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:30.725855Z","time spent":"408.158337ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:16:31.179179Z","caller":"traceutil/trace.go:171","msg":"trace[229534916] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"132.153901ms","start":"2026-08-04T15:16:31.046998Z","end":"2026-08-04T15:16:31.179152Z","steps":["trace[229534916] 'process raft request' (duration: 129.639556ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:16:46.380233Z","caller":"traceutil/trace.go:171","msg":"trace[1099074895] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"274.845664ms","start":"2026-08-04T15:16:46.105351Z","end":"2026-08-04T15:16:46.380196Z","steps":["trace[1099074895] 'process raft request' (duration: 198.724591ms)","trace[1099074895] 'compare' (duration: 75.922988ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:17:14.285447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.76635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:17:14.285547Z","caller":"traceutil/trace.go:171","msg":"trace[725362555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1200; }","duration":"151.890133ms","start":"2026-08-04T15:17:14.133638Z","end":"2026-08-04T15:17:14.285528Z","steps":["trace[725362555] 'range keys from in-memory index tree' (duration: 151.601976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:18:40.81318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.11761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:18:40.813287Z","caller":"traceutil/trace.go:171","msg":"trace[26826755] linearizableReadLoop","detail":"{readStateIndex:2214; appliedIndex:2213; }","duration":"410.705283ms","start":"2026-08-04T15:18:40.402548Z","end":"2026-08-04T15:18:40.813253Z","steps":["trace[26826755] 'read index received' (duration: 193.351989ms)","trace[26826755] 'applied index is now lower than readState.Index' (duration: 217.352184ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:18:40.814129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.576547ms","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-08-04T15:18:40.814162Z","caller":"traceutil/trace.go:171","msg":"trace[1672742903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2130; }","duration":"411.634269ms","start":"2026-08-04T15:18:40.402519Z","end":"2026-08-04T15:18:40.814154Z","steps":["trace[1672742903] 'agreement among raft nodes before linearized reading' (duration: 411.521167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:18:40.814265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:18:40.40251Z","time spent":"411.74408ms","remote":"127.0.0.1:32934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-04T15:18:40.814336Z","caller":"traceutil/trace.go:171","msg":"trace[1476547122] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"414.827699ms","start":"2026-08-04T15:18:40.399017Z","end":"2026-08-04T15:18:40.813844Z","steps":["trace[1476547122] 'process raft request' (duration: 196.866845ms)","trace[1476547122] 'compare' (duration: 216.990338ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:18:40.814496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:18:40.398993Z","time spent":"415.437909ms","remote":"127.0.0.1:32934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:18:40.814526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.664228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-04T15:18:40.814688Z","caller":"traceutil/trace.go:171","msg":"trace[1363975126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2130; }","duration":"406.877062ms","start":"2026-08-04T15:18:40.40779Z","end":"2026-08-04T15:18:40.814667Z","steps":["trace[1363975126] 'agreement among raft nodes before linearized reading' (duration: 406.466665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:18:40.814815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:18:40.407773Z","time spent":"406.991323ms","remote":"127.0.0.1:32934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-04T15:18:41.068351Z","caller":"traceutil/trace.go:171","msg":"trace[1332348690] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"203.832046ms","start":"2026-08-04T15:18:40.864489Z","end":"2026-08-04T15:18:41.068321Z","steps":["trace[1332348690] 'process raft request' (duration: 137.41036ms)","trace[1332348690] 'compare' (duration: 66.212801ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:20:17.551359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.81256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:20:17.551553Z","caller":"traceutil/trace.go:171","msg":"trace[1787137631] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"263.926361ms","start":"2026-08-04T15:20:17.287604Z","end":"2026-08-04T15:20:17.551531Z","steps":["trace[1787137631] 'process raft request' (duration: 108.826112ms)","trace[1787137631] 'compare' (duration: 154.564582ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:20:17.551572Z","caller":"traceutil/trace.go:171","msg":"trace[942147922] linearizableReadLoop","detail":"{readStateIndex:2865; appliedIndex:2864; }","duration":"201.793818ms","start":"2026-08-04T15:20:17.349679Z","end":"2026-08-04T15:20:17.551473Z","steps":["trace[942147922] 'read index received' (duration: 46.74778ms)","trace[942147922] 'applied index is now lower than readState.Index' (duration: 155.044638ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:20:17.551954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.262022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-04T15:20:17.552091Z","caller":"traceutil/trace.go:171","msg":"trace[1356188087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2758; }","duration":"202.365277ms","start":"2026-08-04T15:20:17.349638Z","end":"2026-08-04T15:20:17.552003Z","steps":["trace[1356188087] 'agreement among raft nodes before linearized reading' (duration: 202.15285ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:22.823879Z","caller":"traceutil/trace.go:171","msg":"trace[1716064428] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"120.123993ms","start":"2026-08-04T15:20:22.703728Z","end":"2026-08-04T15:20:22.823852Z","steps":["trace[1716064428] 'process raft request' (duration: 119.949027ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:22.847536Z","caller":"traceutil/trace.go:171","msg":"trace[614962063] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"112.601717ms","start":"2026-08-04T15:20:22.734914Z","end":"2026-08-04T15:20:22.847516Z","steps":["trace[614962063] 'process raft request' (duration: 112.477444ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:03.767231Z","caller":"traceutil/trace.go:171","msg":"trace[1671115292] linearizableReadLoop","detail":"{readStateIndex:4827; appliedIndex:4826; }","duration":"417.99674ms","start":"2026-08-04T15:24:03.349211Z","end":"2026-08-04T15:24:03.767207Z","steps":["trace[1671115292] 'read index received' (duration: 417.917668ms)","trace[1671115292] 'applied index is now lower than readState.Index' (duration: 78.112µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:24:03.767299Z","caller":"traceutil/trace.go:171","msg":"trace[423130015] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"502.88759ms","start":"2026-08-04T15:24:03.264356Z","end":"2026-08-04T15:24:03.767244Z","steps":["trace[423130015] 'process raft request' (duration: 502.679056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:03.767448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.216875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-04T15:24:03.76747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:03.264329Z","time spent":"503.053384ms","remote":"127.0.0.1:47868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:24:03.767491Z","caller":"traceutil/trace.go:171","msg":"trace[894701457] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4644; }","duration":"418.307567ms","start":"2026-08-04T15:24:03.349173Z","end":"2026-08-04T15:24:03.76748Z","steps":["trace[894701457] 'agreement among raft nodes before linearized reading' (duration: 418.154554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:03.767534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:03.349155Z","time spent":"418.370068ms","remote":"127.0.0.1:32934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-04T15:24:03.768321Z","caller":"traceutil/trace.go:171","msg":"trace[82199802] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"161.965871ms","start":"2026-08-04T15:24:03.606326Z","end":"2026-08-04T15:24:03.768292Z","steps":["trace[82199802] 'process raft request' (duration: 161.694176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:03.768344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.538054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-gw8z5\" ","response":"range_response_count:1 size:5148"} {"level":"info","ts":"2026-08-04T15:24:03.768421Z","caller":"traceutil/trace.go:171","msg":"trace[1171008665] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-gw8z5; range_end:; response_count:1; response_revision:4646; }","duration":"354.644075ms","start":"2026-08-04T15:24:03.413754Z","end":"2026-08-04T15:24:03.768398Z","steps":["trace[1171008665] 'agreement among raft nodes before linearized reading' (duration: 354.424211ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:03.768475Z","caller":"traceutil/trace.go:171","msg":"trace[627637513] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"158.750149ms","start":"2026-08-04T15:24:03.609706Z","end":"2026-08-04T15:24:03.768456Z","steps":["trace[627637513] 'process raft request' (duration: 158.426723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:03.76857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.203608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:24:03.768622Z","caller":"traceutil/trace.go:171","msg":"trace[1189921219] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4646; }","duration":"157.29753ms","start":"2026-08-04T15:24:03.61131Z","end":"2026-08-04T15:24:03.768608Z","steps":["trace[1189921219] 'agreement among raft nodes before linearized reading' (duration: 157.214219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:03.768492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:03.413739Z","time spent":"354.736248ms","remote":"127.0.0.1:32878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":5170,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-gw8z5\" "} {"level":"warn","ts":"2026-08-04T15:24:09.953747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.389798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:24:09.953963Z","caller":"traceutil/trace.go:171","msg":"trace[1195884206] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"134.780639ms","start":"2026-08-04T15:24:09.819164Z","end":"2026-08-04T15:24:09.953944Z","steps":["trace[1195884206] 'process raft request' (duration: 33.132777ms)","trace[1195884206] 'compare' (duration: 101.176313ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:24:10.084659Z","caller":"traceutil/trace.go:171","msg":"trace[974476282] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"132.156368ms","start":"2026-08-04T15:24:09.952479Z","end":"2026-08-04T15:24:10.084635Z","steps":["trace[974476282] 'process raft request' (duration: 115.370956ms)","trace[974476282] 'compare' (duration: 16.66819ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:24:10.109504Z","caller":"traceutil/trace.go:171","msg":"trace[318681057] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"148.817167ms","start":"2026-08-04T15:24:09.960647Z","end":"2026-08-04T15:24:10.109464Z","steps":["trace[318681057] 'process raft request' (duration: 148.665854ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:16.138729Z","caller":"traceutil/trace.go:171","msg":"trace[287214634] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"114.400583ms","start":"2026-08-04T15:24:16.024292Z","end":"2026-08-04T15:24:16.138692Z","steps":["trace[287214634] 'process raft request' (duration: 114.26754ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:22.506757Z","caller":"traceutil/trace.go:171","msg":"trace[351140423] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"140.539186ms","start":"2026-08-04T15:24:22.366161Z","end":"2026-08-04T15:24:22.5067Z","steps":["trace[351140423] 'process raft request' (duration: 140.420864ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:47.93485Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2921} {"level":"info","ts":"2026-08-04T15:25:48.020354Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2921,"took":"83.985033ms","hash":994370752,"current-db-size-bytes":53153792,"current-db-size":"53 MB","current-db-size-in-use-bytes":29446144,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-04T15:25:48.020427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":994370752,"revision":2921,"compact-revision":-1} {"level":"warn","ts":"2026-08-04T15:28:03.232344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.137995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:28:03.232593Z","caller":"traceutil/trace.go:171","msg":"trace[1455597795] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:6534; }","duration":"419.347099ms","start":"2026-08-04T15:28:02.813127Z","end":"2026-08-04T15:28:03.232474Z","steps":["trace[1455597795] 'count revisions from in-memory index tree' (duration: 419.026934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:03.232758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:28:02.813106Z","time spent":"419.628175ms","remote":"127.0.0.1:39306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:28:03.236486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.32992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:28:03.236601Z","caller":"traceutil/trace.go:171","msg":"trace[434902822] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:6534; }","duration":"318.515074ms","start":"2026-08-04T15:28:02.918045Z","end":"2026-08-04T15:28:03.23656Z","steps":["trace[434902822] 'count revisions from in-memory index tree' (duration: 318.232019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:03.236656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:28:02.91802Z","time spent":"318.622347ms","remote":"127.0.0.1:32926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-08-04T15:28:26.332981Z","caller":"traceutil/trace.go:171","msg":"trace[361012888] transaction","detail":"{read_only:false; response_revision:6709; number_of_response:1; }","duration":"227.903895ms","start":"2026-08-04T15:28:26.105049Z","end":"2026-08-04T15:28:26.332953Z","steps":["trace[361012888] 'process raft request' (duration: 138.065235ms)","trace[361012888] 'compare' (duration: 89.534507ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:26.375666Z","caller":"traceutil/trace.go:171","msg":"trace[1565311774] linearizableReadLoop","detail":"{readStateIndex:6961; appliedIndex:6959; }","duration":"235.616753ms","start":"2026-08-04T15:28:26.140021Z","end":"2026-08-04T15:28:26.375638Z","steps":["trace[1565311774] 'read index received' (duration: 103.109181ms)","trace[1565311774] 'applied index is now lower than readState.Index' (duration: 132.505542ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:28:26.375839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.803731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:28:26.37592Z","caller":"traceutil/trace.go:171","msg":"trace[300335575] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:6709; }","duration":"235.923617ms","start":"2026-08-04T15:28:26.139975Z","end":"2026-08-04T15:28:26.375899Z","steps":["trace[300335575] 'agreement among raft nodes before linearized reading' (duration: 235.758789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:26.376382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.725801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:28:26.376436Z","caller":"traceutil/trace.go:171","msg":"trace[1807771030] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:6709; }","duration":"215.133989ms","start":"2026-08-04T15:28:26.161287Z","end":"2026-08-04T15:28:26.376421Z","steps":["trace[1807771030] 'agreement among raft nodes before linearized reading' (duration: 214.499531ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:30:47.941112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5619} {"level":"info","ts":"2026-08-04T15:30:48.011683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5619,"took":"69.047599ms","hash":3672214944,"current-db-size-bytes":53153792,"current-db-size":"53 MB","current-db-size-in-use-bytes":18264064,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-04T15:30:48.011735Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3672214944,"revision":5619,"compact-revision":2921}