{"level":"warn","ts":"2026-05-21T10:34:27.273342Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T10:34:27.273428Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-05-21T10:34:27.273497Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T10:34:27.273608Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-21T10:34:27.27364Z","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-05-21T10:34:27.27436Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-05-21T10:34:27.274506Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-05-21T10:34:27.278495Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.750557ms"} {"level":"info","ts":"2026-05-21T10:34:27.285738Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-05-21T10:34:27.285831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T10:34:27.285857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-05-21T10:34:27.285875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T10:34:27.285888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-05-21T10:34:27.285942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-05-21T10:34:27.295325Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T10:34:27.296894Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T10:34:27.298175Z","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-05-21T10:34:27.30025Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T10:34:27.300384Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T10:34:27.300477Z","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-05-21T10:34:27.300657Z","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-05-21T10:34:27.300674Z","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-05-21T10:34:27.302455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-05-21T10:34:27.304404Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-21T10:34:27.307833Z","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-05-21T10:34:27.307891Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-21T10:34:27.307946Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-21T10:34:27.308195Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T10:34:27.308258Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T10:34:27.587219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T10:34:27.587343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T10:34:27.587377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-05-21T10:34:27.587446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-05-21T10:34:27.587467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-21T10:34:27.587486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-05-21T10:34:27.587545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-21T10:34:27.588902Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T10:34:27.589393Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T10:34:27.589411Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T10:34:27.589851Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T10:34:27.589856Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T10:34:27.589924Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T10:34:27.589992Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T10:34:27.59006Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T10:34:27.592821Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T10:34:27.593391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-05-21T10:34:27.594678Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T10:35:03.902725Z","caller":"traceutil/trace.go:171","msg":"trace[481232863] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"119.896925ms","start":"2026-05-21T10:35:03.7828Z","end":"2026-05-21T10:35:03.902697Z","steps":["trace[481232863] 'process raft request' (duration: 119.740234ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:03.909578Z","caller":"traceutil/trace.go:171","msg":"trace[1129213078] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"126.157352ms","start":"2026-05-21T10:35:03.783396Z","end":"2026-05-21T10:35:03.909553Z","steps":["trace[1129213078] 'process raft request' (duration: 125.419725ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:03.909727Z","caller":"traceutil/trace.go:171","msg":"trace[1520879738] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"126.250443ms","start":"2026-05-21T10:35:03.783469Z","end":"2026-05-21T10:35:03.909719Z","steps":["trace[1520879738] 'process raft request' (duration: 125.447576ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:03.909825Z","caller":"traceutil/trace.go:171","msg":"trace[427370593] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"117.372503ms","start":"2026-05-21T10:35:03.792446Z","end":"2026-05-21T10:35:03.909818Z","steps":["trace[427370593] 'process raft request' (duration: 116.499815ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:03.909904Z","caller":"traceutil/trace.go:171","msg":"trace[1970923453] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"116.040341ms","start":"2026-05-21T10:35:03.793857Z","end":"2026-05-21T10:35:03.909898Z","steps":["trace[1970923453] 'process raft request' (duration: 115.114103ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:03.909976Z","caller":"traceutil/trace.go:171","msg":"trace[2146388482] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"116.04614ms","start":"2026-05-21T10:35:03.793925Z","end":"2026-05-21T10:35:03.909971Z","steps":["trace[2146388482] 'process raft request' (duration: 115.076492ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:03.910047Z","caller":"traceutil/trace.go:171","msg":"trace[249211499] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"109.070529ms","start":"2026-05-21T10:35:03.800972Z","end":"2026-05-21T10:35:03.910042Z","steps":["trace[249211499] 'process raft request' (duration: 108.05802ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:03.910117Z","caller":"traceutil/trace.go:171","msg":"trace[1002353004] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"108.849157ms","start":"2026-05-21T10:35:03.801263Z","end":"2026-05-21T10:35:03.910112Z","steps":["trace[1002353004] 'process raft request' (duration: 107.803417ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:07.356487Z","caller":"traceutil/trace.go:171","msg":"trace[643568740] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"110.243319ms","start":"2026-05-21T10:35:07.246194Z","end":"2026-05-21T10:35:07.356437Z","steps":["trace[643568740] 'process raft request' (duration: 109.942806ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:07.437342Z","caller":"traceutil/trace.go:171","msg":"trace[1933635406] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"131.220952ms","start":"2026-05-21T10:35:07.306095Z","end":"2026-05-21T10:35:07.437316Z","steps":["trace[1933635406] 'process raft request' (duration: 129.124764ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:10.904818Z","caller":"traceutil/trace.go:171","msg":"trace[1312753169] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"393.347349ms","start":"2026-05-21T10:35:10.511448Z","end":"2026-05-21T10:35:10.904795Z","steps":["trace[1312753169] 'read index received' (duration: 393.165257ms)","trace[1312753169] 'applied index is now lower than readState.Index' (duration: 181.332µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:35:10.904953Z","caller":"traceutil/trace.go:171","msg":"trace[1952450002] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"413.418492ms","start":"2026-05-21T10:35:10.491524Z","end":"2026-05-21T10:35:10.904942Z","steps":["trace[1952450002] 'process raft request' (duration: 413.14938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:35:10.905256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.808193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lxz8g\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-21T10:35:10.905332Z","caller":"traceutil/trace.go:171","msg":"trace[903857831] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lxz8g; range_end:; response_count:1; response_revision:598; }","duration":"393.908854ms","start":"2026-05-21T10:35:10.511403Z","end":"2026-05-21T10:35:10.905312Z","steps":["trace[903857831] 'agreement among raft nodes before linearized reading' (duration: 393.627142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:35:10.905369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:35:10.51139Z","time spent":"393.972625ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-lxz8g\" "} {"level":"warn","ts":"2026-05-21T10:35:10.905218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.424507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-21T10:35:10.905441Z","caller":"traceutil/trace.go:171","msg":"trace[560709515] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:598; }","duration":"132.689619ms","start":"2026-05-21T10:35:10.772721Z","end":"2026-05-21T10:35:10.905411Z","steps":["trace[560709515] 'agreement among raft nodes before linearized reading' (duration: 132.310836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:35:10.906314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:35:10.491491Z","time spent":"413.617114ms","remote":"127.0.0.1:36608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T10:35:25.689546Z","caller":"traceutil/trace.go:171","msg":"trace[818370565] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"158.590141ms","start":"2026-05-21T10:35:25.530926Z","end":"2026-05-21T10:35:25.689516Z","steps":["trace[818370565] 'process raft request' (duration: 158.349881ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:54.36974Z","caller":"traceutil/trace.go:171","msg":"trace[1432418460] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"113.064595ms","start":"2026-05-21T10:35:54.256645Z","end":"2026-05-21T10:35:54.369709Z","steps":["trace[1432418460] 'process raft request' (duration: 112.367654ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:35:54.370163Z","caller":"traceutil/trace.go:171","msg":"trace[1657582613] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"113.19776ms","start":"2026-05-21T10:35:54.256918Z","end":"2026-05-21T10:35:54.370116Z","steps":["trace[1657582613] 'process raft request' (duration: 112.751717ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:36:40.811932Z","caller":"traceutil/trace.go:171","msg":"trace[272341460] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"172.199704ms","start":"2026-05-21T10:36:40.639686Z","end":"2026-05-21T10:36:40.811886Z","steps":["trace[272341460] 'process raft request' (duration: 171.909178ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:36:40.840012Z","caller":"traceutil/trace.go:171","msg":"trace[841507558] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"199.696269ms","start":"2026-05-21T10:36:40.640296Z","end":"2026-05-21T10:36:40.839992Z","steps":["trace[841507558] 'process raft request' (duration: 199.549156ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:36:40.840341Z","caller":"traceutil/trace.go:171","msg":"trace[2133474981] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1902; }","duration":"169.508292ms","start":"2026-05-21T10:36:40.670446Z","end":"2026-05-21T10:36:40.839954Z","steps":["trace[2133474981] 'read index received' (duration: 141.113669ms)","trace[2133474981] 'applied index is now lower than readState.Index' (duration: 28.393843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T10:36:40.840586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.121213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T10:36:40.840757Z","caller":"traceutil/trace.go:171","msg":"trace[1586644564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1834; }","duration":"170.294207ms","start":"2026-05-21T10:36:40.670385Z","end":"2026-05-21T10:36:40.840679Z","steps":["trace[1586644564] 'agreement among raft nodes before linearized reading' (duration: 169.94815ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:36:52.411438Z","caller":"traceutil/trace.go:171","msg":"trace[270672586] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"211.866ms","start":"2026-05-21T10:36:52.199551Z","end":"2026-05-21T10:36:52.411417Z","steps":["trace[270672586] 'process raft request' (duration: 211.736438ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:37:08.591169Z","caller":"traceutil/trace.go:171","msg":"trace[1366094894] linearizableReadLoop","detail":"{readStateIndex:2115; appliedIndex:2114; }","duration":"106.683921ms","start":"2026-05-21T10:37:08.484459Z","end":"2026-05-21T10:37:08.591143Z","steps":["trace[1366094894] 'read index received' (duration: 106.461578ms)","trace[1366094894] 'applied index is now lower than readState.Index' (duration: 221.193µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:37:08.591234Z","caller":"traceutil/trace.go:171","msg":"trace[237311629] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"113.331712ms","start":"2026-05-21T10:37:08.477864Z","end":"2026-05-21T10:37:08.591196Z","steps":["trace[237311629] 'process raft request' (duration: 113.094158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:37:08.591411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.925485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-05-21T10:37:08.591478Z","caller":"traceutil/trace.go:171","msg":"trace[1057070859] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2038; }","duration":"107.031077ms","start":"2026-05-21T10:37:08.484431Z","end":"2026-05-21T10:37:08.591462Z","steps":["trace[1057070859] 'agreement among raft nodes before linearized reading' (duration: 106.810543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:37:15.259057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.729942ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T10:37:15.259182Z","caller":"traceutil/trace.go:171","msg":"trace[1534961416] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"282.325183ms","start":"2026-05-21T10:37:14.976818Z","end":"2026-05-21T10:37:15.259143Z","steps":["trace[1534961416] 'process raft request' (duration: 196.994078ms)","trace[1534961416] 'compare' (duration: 85.108501ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:37:15.259214Z","caller":"traceutil/trace.go:171","msg":"trace[1855393434] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2070; }","duration":"135.906354ms","start":"2026-05-21T10:37:15.123288Z","end":"2026-05-21T10:37:15.259194Z","steps":["trace[1855393434] 'range keys from in-memory index tree' (duration: 135.711181ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:37:15.259183Z","caller":"traceutil/trace.go:171","msg":"trace[1042063877] linearizableReadLoop","detail":"{readStateIndex:2149; appliedIndex:2148; }","duration":"236.860642ms","start":"2026-05-21T10:37:15.022313Z","end":"2026-05-21T10:37:15.259173Z","steps":["trace[1042063877] 'read index received' (duration: 151.523128ms)","trace[1042063877] 'applied index is now lower than readState.Index' (duration: 85.336694ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:37:15.259128Z","caller":"traceutil/trace.go:171","msg":"trace[1051144337] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"206.48326ms","start":"2026-05-21T10:37:15.052621Z","end":"2026-05-21T10:37:15.259104Z","steps":["trace[1051144337] 'process raft request' (duration: 206.438449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:37:15.259765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.444531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-21T10:37:15.259801Z","caller":"traceutil/trace.go:171","msg":"trace[950632581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2072; }","duration":"237.518883ms","start":"2026-05-21T10:37:15.022271Z","end":"2026-05-21T10:37:15.259789Z","steps":["trace[950632581] 'agreement among raft nodes before linearized reading' (duration: 237.030595ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:37:15.421667Z","caller":"traceutil/trace.go:171","msg":"trace[1432204645] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"158.666685ms","start":"2026-05-21T10:37:15.262972Z","end":"2026-05-21T10:37:15.421638Z","steps":["trace[1432204645] 'process raft request' (duration: 143.346135ms)","trace[1432204645] 'compare' (duration: 15.215578ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:37:15.738967Z","caller":"traceutil/trace.go:171","msg":"trace[173174714] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"125.856001ms","start":"2026-05-21T10:37:15.613085Z","end":"2026-05-21T10:37:15.738941Z","steps":["trace[173174714] 'process raft request' (duration: 123.29679ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:37:15.746577Z","caller":"traceutil/trace.go:171","msg":"trace[793406379] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"129.042033ms","start":"2026-05-21T10:37:15.617521Z","end":"2026-05-21T10:37:15.746563Z","steps":["trace[793406379] 'process raft request' (duration: 128.955182ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:38:59.914293Z","caller":"traceutil/trace.go:171","msg":"trace[135733941] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"202.018198ms","start":"2026-05-21T10:38:59.711672Z","end":"2026-05-21T10:38:59.913691Z","steps":["trace[135733941] 'process raft request' (duration: 201.813836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:43.081138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.407003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T10:42:43.081304Z","caller":"traceutil/trace.go:171","msg":"trace[674173258] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4847; }","duration":"301.489003ms","start":"2026-05-21T10:42:42.779801Z","end":"2026-05-21T10:42:43.08129Z","steps":["trace[674173258] 'read index received' (duration: 135.033844ms)","trace[674173258] 'applied index is now lower than readState.Index' (duration: 166.454339ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:42:43.081476Z","caller":"traceutil/trace.go:171","msg":"trace[1420955079] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"302.627461ms","start":"2026-05-21T10:42:42.778833Z","end":"2026-05-21T10:42:43.081461Z","steps":["trace[1420955079] 'process raft request' (duration: 302.388025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:43.081533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:42:42.778806Z","time spent":"302.704253ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1306,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T10:42:43.081641Z","caller":"traceutil/trace.go:171","msg":"trace[171455891] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"323.306891ms","start":"2026-05-21T10:42:42.75829Z","end":"2026-05-21T10:42:43.081597Z","steps":["trace[171455891] 'process raft request' (duration: 157.274382ms)","trace[171455891] 'compare' (duration: 165.171127ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T10:42:43.081834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:42:42.758255Z","time spent":"323.490625ms","remote":"127.0.0.1:36440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T10:42:43.082244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.212302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-21T10:42:43.083486Z","caller":"traceutil/trace.go:171","msg":"trace[594459911] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4671; }","duration":"303.678729ms","start":"2026-05-21T10:42:42.779787Z","end":"2026-05-21T10:42:43.083466Z","steps":["trace[594459911] 'agreement among raft nodes before linearized reading' (duration: 301.673958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:43.083585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:42:42.779748Z","time spent":"303.823202ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-05-21T10:42:43.099558Z","caller":"traceutil/trace.go:171","msg":"trace[620398670] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"119.760464ms","start":"2026-05-21T10:42:42.979776Z","end":"2026-05-21T10:42:43.099537Z","steps":["trace[620398670] 'process raft request' (duration: 119.60242ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:42:43.099809Z","caller":"traceutil/trace.go:171","msg":"trace[1967405975] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"112.536433ms","start":"2026-05-21T10:42:42.987241Z","end":"2026-05-21T10:42:43.099777Z","steps":["trace[1967405975] 'process raft request' (duration: 112.259856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:43.108616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.760907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-21T10:42:43.108686Z","caller":"traceutil/trace.go:171","msg":"trace[549628258] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4676; }","duration":"328.866209ms","start":"2026-05-21T10:42:42.779804Z","end":"2026-05-21T10:42:43.10867Z","steps":["trace[549628258] 'agreement among raft nodes before linearized reading' (duration: 328.729656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:43.108716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T10:42:42.779791Z","time spent":"328.918331ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-05-21T10:42:43.109039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.388866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T10:42:43.109113Z","caller":"traceutil/trace.go:171","msg":"trace[1060186994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4676; }","duration":"174.479048ms","start":"2026-05-21T10:42:42.934619Z","end":"2026-05-21T10:42:43.109098Z","steps":["trace[1060186994] 'agreement among raft nodes before linearized reading' (duration: 174.379175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:43.109236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.986893ms","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-05-21T10:42:43.109278Z","caller":"traceutil/trace.go:171","msg":"trace[597340904] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4676; }","duration":"181.083835ms","start":"2026-05-21T10:42:42.92818Z","end":"2026-05-21T10:42:43.109264Z","steps":["trace[597340904] 'agreement among raft nodes before linearized reading' (duration: 181.012913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:43.490187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.191188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T10:42:43.49051Z","caller":"traceutil/trace.go:171","msg":"trace[1994522935] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"252.745465ms","start":"2026-05-21T10:42:43.237738Z","end":"2026-05-21T10:42:43.490483Z","steps":["trace[1994522935] 'process raft request' (duration: 125.165338ms)","trace[1994522935] 'compare' (duration: 127.052404ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:42:43.490579Z","caller":"traceutil/trace.go:171","msg":"trace[197646106] linearizableReadLoop","detail":"{readStateIndex:4863; appliedIndex:4862; }","duration":"250.375825ms","start":"2026-05-21T10:42:43.240184Z","end":"2026-05-21T10:42:43.49056Z","steps":["trace[197646106] 'read index received' (duration: 122.633794ms)","trace[197646106] 'applied index is now lower than readState.Index' (duration: 127.740491ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T10:42:43.490851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.655842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-21T10:42:43.490892Z","caller":"traceutil/trace.go:171","msg":"trace[725386926] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4685; }","duration":"250.715854ms","start":"2026-05-21T10:42:43.240163Z","end":"2026-05-21T10:42:43.490879Z","steps":["trace[725386926] 'agreement among raft nodes before linearized reading' (duration: 250.503539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:43.512271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.141641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T10:42:43.512365Z","caller":"traceutil/trace.go:171","msg":"trace[27616475] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:4686; }","duration":"118.284515ms","start":"2026-05-21T10:42:43.394058Z","end":"2026-05-21T10:42:43.512342Z","steps":["trace[27616475] 'agreement among raft nodes before linearized reading' (duration: 118.056909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:43.514239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.683301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2196726"} {"level":"info","ts":"2026-05-21T10:42:43.514319Z","caller":"traceutil/trace.go:171","msg":"trace[909308689] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4686; }","duration":"145.815504ms","start":"2026-05-21T10:42:43.368483Z","end":"2026-05-21T10:42:43.514299Z","steps":["trace[909308689] 'agreement among raft nodes before linearized reading' (duration: 143.726702ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:42:45.918726Z","caller":"traceutil/trace.go:171","msg":"trace[2087561481] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4920; }","duration":"179.366261ms","start":"2026-05-21T10:42:45.739341Z","end":"2026-05-21T10:42:45.918707Z","steps":["trace[2087561481] 'read index received' (duration: 179.298649ms)","trace[2087561481] 'applied index is now lower than readState.Index' (duration: 66.832µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T10:42:45.918795Z","caller":"traceutil/trace.go:171","msg":"trace[1537103961] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"183.048602ms","start":"2026-05-21T10:42:45.73571Z","end":"2026-05-21T10:42:45.918759Z","steps":["trace[1537103961] 'process raft request' (duration: 182.887108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:45.918883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.515575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-05-21T10:42:45.919557Z","caller":"traceutil/trace.go:171","msg":"trace[692842239] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4743; }","duration":"180.232003ms","start":"2026-05-21T10:42:45.739316Z","end":"2026-05-21T10:42:45.919548Z","steps":["trace[692842239] 'agreement among raft nodes before linearized reading' (duration: 179.472494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T10:42:45.924281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.852379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T10:42:45.92436Z","caller":"traceutil/trace.go:171","msg":"trace[1499637328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4743; }","duration":"162.966571ms","start":"2026-05-21T10:42:45.761375Z","end":"2026-05-21T10:42:45.924341Z","steps":["trace[1499637328] 'agreement among raft nodes before linearized reading' (duration: 162.736956ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T10:44:27.699251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2886} {"level":"info","ts":"2026-05-21T10:44:27.771124Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2886,"took":"70.409577ms","hash":3314721832,"current-db-size-bytes":53936128,"current-db-size":"54 MB","current-db-size-in-use-bytes":29540352,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-21T10:44:27.771276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3314721832,"revision":2886,"compact-revision":-1} {"level":"info","ts":"2026-05-21T10:49:27.704793Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5636} {"level":"info","ts":"2026-05-21T10:49:27.784702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5636,"took":"78.75114ms","hash":1137199158,"current-db-size-bytes":53936128,"current-db-size":"54 MB","current-db-size-in-use-bytes":18288640,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-21T10:49:27.784771Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1137199158,"revision":5636,"compact-revision":2886}