{"level":"warn","ts":"2026-06-30T22:11:05.839897Z","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-30T22:11:05.839971Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-30T22:11:05.840028Z","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-30T22:11:05.840043Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-30T22:11:05.840062Z","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-30T22:11:05.840707Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-06-30T22:11:05.840776Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-30T22:11:05.845101Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.141381ms"} {"level":"info","ts":"2026-06-30T22:11:05.852547Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-06-30T22:11:05.852633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-06-30T22:11:05.852668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-06-30T22:11:05.852688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-30T22:11:05.85271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-06-30T22:11:05.852757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-06-30T22:11:05.85931Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-30T22:11:05.862034Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-30T22:11:05.865509Z","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-30T22:11:05.867502Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-30T22:11:05.867647Z","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-30T22:11:05.867737Z","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-30T22:11:05.867747Z","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-30T22:11:05.86791Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-30T22:11:05.869987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-06-30T22:11:05.870168Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-30T22:11:05.871042Z","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-30T22:11:05.871191Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-30T22:11:05.871252Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-30T22:11:05.871456Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-30T22:11:05.871495Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-30T22:11:06.653543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-06-30T22:11:06.653586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-06-30T22:11:06.653616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-06-30T22:11:06.653631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-06-30T22:11:06.653639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-30T22:11:06.65365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-06-30T22:11:06.653659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-30T22:11:06.654636Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T22:11:06.655316Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-06-30T22:11:06.655318Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T22:11:06.655378Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T22:11:06.655562Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-30T22:11:06.655599Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-30T22:11:06.656217Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T22:11:06.656289Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T22:11:06.656306Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T22:11:06.658582Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-06-30T22:11:06.659936Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-30T22:11:41.988705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.664204ms","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-30T22:11:41.988902Z","caller":"traceutil/trace.go:171","msg":"trace[498553791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:478; }","duration":"156.915441ms","start":"2026-06-30T22:11:41.83197Z","end":"2026-06-30T22:11:41.988885Z","steps":["trace[498553791] 'range keys from in-memory index tree' (duration: 156.581692ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:41.988785Z","caller":"traceutil/trace.go:171","msg":"trace[275701747] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"153.359755ms","start":"2026-06-30T22:11:41.835398Z","end":"2026-06-30T22:11:41.988758Z","steps":["trace[275701747] 'process raft request' (duration: 124.185019ms)","trace[275701747] 'compare' (duration: 29.072323ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T22:11:42.018741Z","caller":"traceutil/trace.go:171","msg":"trace[1592239431] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"132.037158ms","start":"2026-06-30T22:11:41.886685Z","end":"2026-06-30T22:11:42.018722Z","steps":["trace[1592239431] 'process raft request' (duration: 131.915124ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:42.018785Z","caller":"traceutil/trace.go:171","msg":"trace[1316141819] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"137.168446ms","start":"2026-06-30T22:11:41.881532Z","end":"2026-06-30T22:11:42.018701Z","steps":["trace[1316141819] 'process raft request' (duration: 136.94801ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:42.018975Z","caller":"traceutil/trace.go:171","msg":"trace[1916201796] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"132.071169ms","start":"2026-06-30T22:11:41.886733Z","end":"2026-06-30T22:11:42.018804Z","steps":["trace[1916201796] 'process raft request' (duration: 131.909785ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:42.019199Z","caller":"traceutil/trace.go:171","msg":"trace[1649427936] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"121.662408ms","start":"2026-06-30T22:11:41.897521Z","end":"2026-06-30T22:11:42.019183Z","steps":["trace[1649427936] 'process raft request' (duration: 121.157285ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:42.019397Z","caller":"traceutil/trace.go:171","msg":"trace[1205277086] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"120.361503ms","start":"2026-06-30T22:11:41.899022Z","end":"2026-06-30T22:11:42.019384Z","steps":["trace[1205277086] 'process raft request' (duration: 119.896121ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:42.019501Z","caller":"traceutil/trace.go:171","msg":"trace[70662618] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"120.423705ms","start":"2026-06-30T22:11:41.899056Z","end":"2026-06-30T22:11:42.01948Z","steps":["trace[70662618] 'process raft request' (duration: 120.034044ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:42.019915Z","caller":"traceutil/trace.go:171","msg":"trace[1950314996] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"120.704452ms","start":"2026-06-30T22:11:41.899193Z","end":"2026-06-30T22:11:42.019897Z","steps":["trace[1950314996] 'process raft request' (duration: 120.016214ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:42.020114Z","caller":"traceutil/trace.go:171","msg":"trace[2039682805] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"107.562038ms","start":"2026-06-30T22:11:41.912538Z","end":"2026-06-30T22:11:42.0201Z","steps":["trace[2039682805] 'process raft request' (duration: 107.261329ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:42.020198Z","caller":"traceutil/trace.go:171","msg":"trace[389450412] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"114.022122ms","start":"2026-06-30T22:11:41.906167Z","end":"2026-06-30T22:11:42.020189Z","steps":["trace[389450412] 'process raft request' (duration: 113.551599ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:42.019405Z","caller":"traceutil/trace.go:171","msg":"trace[1035289588] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"120.383023ms","start":"2026-06-30T22:11:41.898993Z","end":"2026-06-30T22:11:42.019376Z","steps":["trace[1035289588] 'process raft request' (duration: 119.721965ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:48.468726Z","caller":"traceutil/trace.go:171","msg":"trace[573660850] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"185.096915ms","start":"2026-06-30T22:11:48.283575Z","end":"2026-06-30T22:11:48.468671Z","steps":["trace[573660850] 'read index received' (duration: 177.563411ms)","trace[573660850] 'applied index is now lower than readState.Index' (duration: 7.532024ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T22:11:48.469029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.415685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8948p\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-30T22:11:48.469076Z","caller":"traceutil/trace.go:171","msg":"trace[1730361253] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8948p; range_end:; response_count:1; response_revision:587; }","duration":"185.519777ms","start":"2026-06-30T22:11:48.283539Z","end":"2026-06-30T22:11:48.469059Z","steps":["trace[1730361253] 'agreement among raft nodes before linearized reading' (duration: 185.265271ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:48.469354Z","caller":"traceutil/trace.go:171","msg":"trace[1157853051] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"191.164094ms","start":"2026-06-30T22:11:48.277993Z","end":"2026-06-30T22:11:48.469157Z","steps":["trace[1157853051] 'process raft request' (duration: 183.812513ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:48.711616Z","caller":"traceutil/trace.go:171","msg":"trace[70457260] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"227.098166ms","start":"2026-06-30T22:11:48.484485Z","end":"2026-06-30T22:11:48.711583Z","steps":["trace[70457260] 'process raft request' (duration: 226.87104ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:11:48.722771Z","caller":"traceutil/trace.go:171","msg":"trace[1223481341] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"172.851708ms","start":"2026-06-30T22:11:48.549897Z","end":"2026-06-30T22:11:48.722748Z","steps":["trace[1223481341] 'read index received' (duration: 161.485284ms)","trace[1223481341] 'applied index is now lower than readState.Index' (duration: 11.365744ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T22:11:48.722814Z","caller":"traceutil/trace.go:171","msg":"trace[1165287709] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"202.692552ms","start":"2026-06-30T22:11:48.520079Z","end":"2026-06-30T22:11:48.722771Z","steps":["trace[1165287709] 'process raft request' (duration: 202.553388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:11:48.722915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.003202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T22:11:48.723Z","caller":"traceutil/trace.go:171","msg":"trace[541566580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"173.099765ms","start":"2026-06-30T22:11:48.54989Z","end":"2026-06-30T22:11:48.72299Z","steps":["trace[541566580] 'agreement among raft nodes before linearized reading' (duration: 172.967581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:11:48.723157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.764848ms","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-06-30T22:11:48.723216Z","caller":"traceutil/trace.go:171","msg":"trace[2026582172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"149.85264ms","start":"2026-06-30T22:11:48.573346Z","end":"2026-06-30T22:11:48.723199Z","steps":["trace[2026582172] 'agreement among raft nodes before linearized reading' (duration: 149.613634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:11:49.380089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.611364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-30T22:11:49.380439Z","caller":"traceutil/trace.go:171","msg":"trace[1180617817] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"635.201312ms","start":"2026-06-30T22:11:48.74519Z","end":"2026-06-30T22:11:49.380391Z","steps":["trace[1180617817] 'process raft request' (duration: 455.693265ms)","trace[1180617817] 'compare' (duration: 178.244544ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T22:11:49.380422Z","caller":"traceutil/trace.go:171","msg":"trace[1999770163] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"427.1015ms","start":"2026-06-30T22:11:48.953221Z","end":"2026-06-30T22:11:49.380323Z","steps":["trace[1999770163] 'process raft request' (duration: 426.967896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:11:49.380534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T22:11:48.745169Z","time spent":"635.326555ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-30T22:11:49.380918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T22:11:48.953192Z","time spent":"427.334896ms","remote":"127.0.0.1:36216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T22:12:05.24022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.53762ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-06-30T22:12:34.664842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.978947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T22:12:34.664931Z","caller":"traceutil/trace.go:171","msg":"trace[894876837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1154; }","duration":"116.11441ms","start":"2026-06-30T22:12:34.548799Z","end":"2026-06-30T22:12:34.664913Z","steps":["trace[894876837] 'range keys from in-memory index tree' (duration: 115.883796ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:13:36.086605Z","caller":"traceutil/trace.go:171","msg":"trace[1600460032] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"136.897614ms","start":"2026-06-30T22:13:35.949667Z","end":"2026-06-30T22:13:36.086564Z","steps":["trace[1600460032] 'process raft request' (duration: 52.796682ms)","trace[1600460032] 'compare' (duration: 83.794937ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T22:13:36.377107Z","caller":"traceutil/trace.go:171","msg":"trace[1847531532] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"131.129653ms","start":"2026-06-30T22:13:36.245947Z","end":"2026-06-30T22:13:36.377076Z","steps":["trace[1847531532] 'read index received' (duration: 124.732182ms)","trace[1847531532] 'applied index is now lower than readState.Index' (duration: 6.396461ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T22:13:36.377223Z","caller":"traceutil/trace.go:171","msg":"trace[218921580] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"206.258557ms","start":"2026-06-30T22:13:36.170929Z","end":"2026-06-30T22:13:36.377188Z","steps":["trace[218921580] 'process raft request' (duration: 199.772054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:13:36.377543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.49525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"info","ts":"2026-06-30T22:13:36.377606Z","caller":"traceutil/trace.go:171","msg":"trace[927274286] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1867; }","duration":"131.725845ms","start":"2026-06-30T22:13:36.245864Z","end":"2026-06-30T22:13:36.377589Z","steps":["trace[927274286] 'agreement among raft nodes before linearized reading' (duration: 131.363088ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:13:49.375581Z","caller":"traceutil/trace.go:171","msg":"trace[1800004377] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"101.492343ms","start":"2026-06-30T22:13:49.274029Z","end":"2026-06-30T22:13:49.375521Z","steps":["trace[1800004377] 'process raft request' (duration: 101.32291ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:14:10.374657Z","caller":"traceutil/trace.go:171","msg":"trace[326496449] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"132.138518ms","start":"2026-06-30T22:14:10.242487Z","end":"2026-06-30T22:14:10.374625Z","steps":["trace[326496449] 'process raft request' (duration: 131.963055ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:14:10.419257Z","caller":"traceutil/trace.go:171","msg":"trace[1404279723] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2188; }","duration":"134.97473ms","start":"2026-06-30T22:14:10.284262Z","end":"2026-06-30T22:14:10.419237Z","steps":["trace[1404279723] 'read index received' (duration: 90.206901ms)","trace[1404279723] 'applied index is now lower than readState.Index' (duration: 44.766929ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T22:14:10.419309Z","caller":"traceutil/trace.go:171","msg":"trace[1806637992] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"174.645625ms","start":"2026-06-30T22:14:10.244644Z","end":"2026-06-30T22:14:10.41929Z","steps":["trace[1806637992] 'process raft request' (duration: 174.482832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:14:10.41944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.164684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-30T22:14:10.41952Z","caller":"traceutil/trace.go:171","msg":"trace[1228054370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2108; }","duration":"135.264966ms","start":"2026-06-30T22:14:10.284201Z","end":"2026-06-30T22:14:10.419466Z","steps":["trace[1228054370] 'agreement among raft nodes before linearized reading' (duration: 135.121213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:15:15.376408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.052534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-06-30T22:15:15.376489Z","caller":"traceutil/trace.go:171","msg":"trace[2147355333] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2558; }","duration":"282.169446ms","start":"2026-06-30T22:15:15.094306Z","end":"2026-06-30T22:15:15.376476Z","steps":["trace[2147355333] 'range keys from in-memory index tree' (duration: 281.919182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:15:15.376649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.368944ms","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-30T22:15:15.376666Z","caller":"traceutil/trace.go:171","msg":"trace[972890801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2558; }","duration":"210.419095ms","start":"2026-06-30T22:15:15.166241Z","end":"2026-06-30T22:15:15.37666Z","steps":["trace[972890801] 'range keys from in-memory index tree' (duration: 210.163061ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:15:47.939029Z","caller":"traceutil/trace.go:171","msg":"trace[1594761768] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"102.582256ms","start":"2026-06-30T22:15:47.836424Z","end":"2026-06-30T22:15:47.939006Z","steps":["trace[1594761768] 'process raft request' (duration: 102.455213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:15:48.388358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.466887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T22:15:48.388498Z","caller":"traceutil/trace.go:171","msg":"trace[1963388296] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"401.727284ms","start":"2026-06-30T22:15:47.986741Z","end":"2026-06-30T22:15:48.388468Z","steps":["trace[1963388296] 'process raft request' (duration: 158.070022ms)","trace[1963388296] 'compare' (duration: 243.253613ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T22:15:48.388649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T22:15:47.986722Z","time spent":"401.844626ms","remote":"127.0.0.1:36216","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-30T22:15:48.388463Z","caller":"traceutil/trace.go:171","msg":"trace[1863902469] linearizableReadLoop","detail":"{readStateIndex:2844; appliedIndex:2843; }","duration":"401.066248ms","start":"2026-06-30T22:15:47.987374Z","end":"2026-06-30T22:15:48.38844Z","steps":["trace[1863902469] 'read index received' (duration: 157.328375ms)","trace[1863902469] 'applied index is now lower than readState.Index' (duration: 243.736103ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T22:15:48.388965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.347862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T22:15:48.389152Z","caller":"traceutil/trace.go:171","msg":"trace[650138863] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2740; }","duration":"317.424354ms","start":"2026-06-30T22:15:48.071592Z","end":"2026-06-30T22:15:48.389016Z","steps":["trace[650138863] 'agreement among raft nodes before linearized reading' (duration: 317.313141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:15:48.389332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T22:15:48.071529Z","time spent":"317.68175ms","remote":"127.0.0.1:36328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-30T22:15:48.389923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.548474ms","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-06-30T22:15:48.389968Z","caller":"traceutil/trace.go:171","msg":"trace[1819842217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2740; }","duration":"402.624796ms","start":"2026-06-30T22:15:47.987331Z","end":"2026-06-30T22:15:48.389956Z","steps":["trace[1819842217] 'agreement among raft nodes before linearized reading' (duration: 401.492439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:15:48.390003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T22:15:47.987315Z","time spent":"402.680947ms","remote":"127.0.0.1:36216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-30T22:15:48.497001Z","caller":"traceutil/trace.go:171","msg":"trace[1497646520] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"103.598765ms","start":"2026-06-30T22:15:48.393353Z","end":"2026-06-30T22:15:48.496951Z","steps":["trace[1497646520] 'process raft request' (duration: 100.00234ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:19:32.739071Z","caller":"traceutil/trace.go:171","msg":"trace[1039041312] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"105.072991ms","start":"2026-06-30T22:19:32.633962Z","end":"2026-06-30T22:19:32.739035Z","steps":["trace[1039041312] 'process raft request' (duration: 104.750095ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:19:51.853471Z","caller":"traceutil/trace.go:171","msg":"trace[1493987046] linearizableReadLoop","detail":"{readStateIndex:5068; appliedIndex:5067; }","duration":"224.360374ms","start":"2026-06-30T22:19:51.629078Z","end":"2026-06-30T22:19:51.853438Z","steps":["trace[1493987046] 'read index received' (duration: 224.107329ms)","trace[1493987046] 'applied index is now lower than readState.Index' (duration: 251.045µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T22:19:51.853529Z","caller":"traceutil/trace.go:171","msg":"trace[1200589350] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"286.580052ms","start":"2026-06-30T22:19:51.566917Z","end":"2026-06-30T22:19:51.853497Z","steps":["trace[1200589350] 'process raft request' (duration: 286.314657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:19:51.853734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.630269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-06-30T22:19:51.853766Z","caller":"traceutil/trace.go:171","msg":"trace[853716450] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4882; }","duration":"224.71404ms","start":"2026-06-30T22:19:51.629045Z","end":"2026-06-30T22:19:51.853759Z","steps":["trace[853716450] 'agreement among raft nodes before linearized reading' (duration: 224.538097ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:19:51.881494Z","caller":"traceutil/trace.go:171","msg":"trace[930521337] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"160.248821ms","start":"2026-06-30T22:19:51.721222Z","end":"2026-06-30T22:19:51.881471Z","steps":["trace[930521337] 'process raft request' (duration: 160.075768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:19:51.88171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.3836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-30T22:19:51.881768Z","caller":"traceutil/trace.go:171","msg":"trace[1670053449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4883; }","duration":"155.464002ms","start":"2026-06-30T22:19:51.72629Z","end":"2026-06-30T22:19:51.881754Z","steps":["trace[1670053449] 'agreement among raft nodes before linearized reading' (duration: 155.209937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T22:19:51.881776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.06322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-30T22:19:51.882947Z","caller":"traceutil/trace.go:171","msg":"trace[686586259] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4883; }","duration":"214.391134ms","start":"2026-06-30T22:19:51.66853Z","end":"2026-06-30T22:19:51.882922Z","steps":["trace[686586259] 'agreement among raft nodes before linearized reading' (duration: 212.904947ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T22:21:06.679272Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2836} {"level":"info","ts":"2026-06-30T22:21:06.744772Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2836,"took":"64.454456ms","hash":247479637,"current-db-size-bytes":54095872,"current-db-size":"54 MB","current-db-size-in-use-bytes":29851648,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-30T22:21:06.744838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":247479637,"revision":2836,"compact-revision":-1} {"level":"info","ts":"2026-06-30T22:26:06.687586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5534} {"level":"info","ts":"2026-06-30T22:26:06.763266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5534,"took":"74.455059ms","hash":1570110960,"current-db-size-bytes":54095872,"current-db-size":"54 MB","current-db-size-in-use-bytes":18440192,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-30T22:26:06.763312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1570110960,"revision":5534,"compact-revision":2836}