{"level":"warn","ts":"2026-04-04T10:11:54.053379Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-04T10:11:54.053484Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-04T10:11:54.053581Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-04T10:11:54.053603Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-04T10:11:54.053634Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-04T10:11:54.054751Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-04-04T10:11:54.055126Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-04T10:11:54.058965Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.585083ms"} {"level":"info","ts":"2026-04-04T10:11:54.066189Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-04-04T10:11:54.066249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-04-04T10:11:54.066265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-04-04T10:11:54.066272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-04T10:11:54.066279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-04-04T10:11:54.066433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-04-04T10:11:54.075882Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-04T10:11:54.077656Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-04T10:11:54.078752Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-04T10:11:54.081026Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-04T10:11:54.081292Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-04T10:11:54.081457Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-04T10:11:54.081524Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-04T10:11:54.08154Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-04T10:11:54.081798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-04-04T10:11:54.081896Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-04T10:11:54.086779Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-04T10:11:54.087086Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-04T10:11:54.087124Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-04T10:11:54.087296Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-04T10:11:54.087307Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-04T10:11:55.067021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-04-04T10:11:55.067152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-04T10:11:55.067204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-04-04T10:11:55.06723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-04-04T10:11:55.067243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-04T10:11:55.067267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-04-04T10:11:55.067296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-04T10:11:55.068359Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:11:55.069538Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-04T10:11:55.069551Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:11:55.069804Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:11:55.070042Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-04T10:11:55.070202Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-04T10:11:55.073486Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-04T10:11:55.073551Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-04-04T10:11:55.083515Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:11:55.083581Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:11:55.083605Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:12:24.198649Z","caller":"traceutil/trace.go:171","msg":"trace[1089310194] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"110.707937ms","start":"2026-04-04T10:12:24.087894Z","end":"2026-04-04T10:12:24.198602Z","steps":["trace[1089310194] 'process raft request' (duration: 36.633691ms)","trace[1089310194] 'compare' (duration: 73.85443ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:12:30.753325Z","caller":"traceutil/trace.go:171","msg":"trace[2089320075] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"162.784258ms","start":"2026-04-04T10:12:30.590514Z","end":"2026-04-04T10:12:30.753298Z","steps":["trace[2089320075] 'read index received' (duration: 162.554111ms)","trace[2089320075] 'applied index is now lower than readState.Index' (duration: 229.217µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:12:30.753628Z","caller":"traceutil/trace.go:171","msg":"trace[1645996876] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"275.005522ms","start":"2026-04-04T10:12:30.478578Z","end":"2026-04-04T10:12:30.753583Z","steps":["trace[1645996876] 'process raft request' (duration: 274.546459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:12:30.753729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.19918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-04T10:12:30.753827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.37034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-04T10:12:30.753867Z","caller":"traceutil/trace.go:171","msg":"trace[751152971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"163.388585ms","start":"2026-04-04T10:12:30.590461Z","end":"2026-04-04T10:12:30.75385Z","steps":["trace[751152971] 'agreement among raft nodes before linearized reading' (duration: 163.110147ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:12:30.753904Z","caller":"traceutil/trace.go:171","msg":"trace[1841543900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"156.521714ms","start":"2026-04-04T10:12:30.597359Z","end":"2026-04-04T10:12:30.753881Z","steps":["trace[1841543900] 'agreement among raft nodes before linearized reading' (duration: 156.239726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:12:30.754024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.163764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-04T10:12:30.754067Z","caller":"traceutil/trace.go:171","msg":"trace[1970928835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"156.230906ms","start":"2026-04-04T10:12:30.597825Z","end":"2026-04-04T10:12:30.754055Z","steps":["trace[1970928835] 'agreement among raft nodes before linearized reading' (duration: 156.110443ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:12:30.972346Z","caller":"traceutil/trace.go:171","msg":"trace[147827394] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"141.659451ms","start":"2026-04-04T10:12:30.830662Z","end":"2026-04-04T10:12:30.972322Z","steps":["trace[147827394] 'read index received' (duration: 73.931566ms)","trace[147827394] 'applied index is now lower than readState.Index' (duration: 67.726775ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:12:30.972416Z","caller":"traceutil/trace.go:171","msg":"trace[1380933085] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"211.434547ms","start":"2026-04-04T10:12:30.76094Z","end":"2026-04-04T10:12:30.972375Z","steps":["trace[1380933085] 'process raft request' (duration: 211.327664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:12:30.973059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.928543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-04T10:12:30.972503Z","caller":"traceutil/trace.go:171","msg":"trace[1672497486] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"214.223358ms","start":"2026-04-04T10:12:30.758264Z","end":"2026-04-04T10:12:30.972488Z","steps":["trace[1672497486] 'process raft request' (duration: 146.308067ms)","trace[1672497486] 'compare' (duration: 67.52045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:12:30.972683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.407745ms","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-04-04T10:12:30.973289Z","caller":"traceutil/trace.go:171","msg":"trace[907344579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"203.044943ms","start":"2026-04-04T10:12:30.770225Z","end":"2026-04-04T10:12:30.97327Z","steps":["trace[907344579] 'agreement among raft nodes before linearized reading' (duration: 202.315051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:12:30.97273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.000925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bxq5t\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-04T10:12:30.973416Z","caller":"traceutil/trace.go:171","msg":"trace[1379815724] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bxq5t; range_end:; response_count:1; response_revision:627; }","duration":"188.718406ms","start":"2026-04-04T10:12:30.784675Z","end":"2026-04-04T10:12:30.973394Z","steps":["trace[1379815724] 'agreement among raft nodes before linearized reading' (duration: 187.885831ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:12:30.973168Z","caller":"traceutil/trace.go:171","msg":"trace[1860006934] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:627; }","duration":"139.053647ms","start":"2026-04-04T10:12:30.83409Z","end":"2026-04-04T10:12:30.973144Z","steps":["trace[1860006934] 'agreement among raft nodes before linearized reading' (duration: 138.929433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:12:46.414725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.606119ms","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-04-04T10:12:46.414822Z","caller":"traceutil/trace.go:171","msg":"trace[586340900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"138.754163ms","start":"2026-04-04T10:12:46.276046Z","end":"2026-04-04T10:12:46.4148Z","steps":["trace[586340900] 'range keys from in-memory index tree' (duration: 138.441704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:13:41.283644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.40854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-04T10:13:41.283747Z","caller":"traceutil/trace.go:171","msg":"trace[1950382056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1456; }","duration":"118.558704ms","start":"2026-04-04T10:13:41.165169Z","end":"2026-04-04T10:13:41.283728Z","steps":["trace[1950382056] 'range keys from in-memory index tree' (duration: 118.206483ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:05.468607Z","caller":"traceutil/trace.go:171","msg":"trace[2076762003] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"215.10659ms","start":"2026-04-04T10:14:05.253476Z","end":"2026-04-04T10:14:05.468582Z","steps":["trace[2076762003] 'process raft request' (duration: 214.964576ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:43.882668Z","caller":"traceutil/trace.go:171","msg":"trace[1973561160] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2210; }","duration":"194.150669ms","start":"2026-04-04T10:14:43.688496Z","end":"2026-04-04T10:14:43.882646Z","steps":["trace[1973561160] 'read index received' (duration: 43.945211ms)","trace[1973561160] 'applied index is now lower than readState.Index' (duration: 150.204428ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:14:43.882891Z","caller":"traceutil/trace.go:171","msg":"trace[1515203636] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"218.925718ms","start":"2026-04-04T10:14:43.663935Z","end":"2026-04-04T10:14:43.882861Z","steps":["trace[1515203636] 'process raft request' (duration: 202.699189ms)","trace[1515203636] 'compare' (duration: 15.704098ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:14:43.883156Z","caller":"traceutil/trace.go:171","msg":"trace[219823619] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"217.329367ms","start":"2026-04-04T10:14:43.665816Z","end":"2026-04-04T10:14:43.883145Z","steps":["trace[219823619] 'process raft request' (duration: 216.742536ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:43.8832Z","caller":"traceutil/trace.go:171","msg":"trace[1493589750] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"165.901646ms","start":"2026-04-04T10:14:43.717291Z","end":"2026-04-04T10:14:43.883192Z","steps":["trace[1493589750] 'process raft request' (duration: 165.309536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:14:43.883372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.857419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-04T10:14:43.884588Z","caller":"traceutil/trace.go:171","msg":"trace[1278179819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2138; }","duration":"196.104511ms","start":"2026-04-04T10:14:43.68847Z","end":"2026-04-04T10:14:43.884575Z","steps":["trace[1278179819] 'agreement among raft nodes before linearized reading' (duration: 194.760819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:14:43.883638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.348712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-04T10:14:43.884993Z","caller":"traceutil/trace.go:171","msg":"trace[1350777296] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2138; }","duration":"155.721285ms","start":"2026-04-04T10:14:43.729256Z","end":"2026-04-04T10:14:43.884977Z","steps":["trace[1350777296] 'agreement among raft nodes before linearized reading' (duration: 154.308272ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:44.034812Z","caller":"traceutil/trace.go:171","msg":"trace[1056404227] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"102.163487ms","start":"2026-04-04T10:14:43.932625Z","end":"2026-04-04T10:14:44.034788Z","steps":["trace[1056404227] 'process raft request' (duration: 64.074685ms)","trace[1056404227] 'compare' (duration: 37.993432ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:14:44.353286Z","caller":"traceutil/trace.go:171","msg":"trace[353001069] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"231.474145ms","start":"2026-04-04T10:14:44.121783Z","end":"2026-04-04T10:14:44.353257Z","steps":["trace[353001069] 'process raft request' (duration: 137.306863ms)","trace[353001069] 'compare' (duration: 93.755872ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:15:43.918063Z","caller":"traceutil/trace.go:171","msg":"trace[1949408687] linearizableReadLoop","detail":"{readStateIndex:2651; appliedIndex:2650; }","duration":"254.69979ms","start":"2026-04-04T10:15:43.66331Z","end":"2026-04-04T10:15:43.91801Z","steps":["trace[1949408687] 'read index received' (duration: 235.948385ms)","trace[1949408687] 'applied index is now lower than readState.Index' (duration: 18.750125ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:15:43.918159Z","caller":"traceutil/trace.go:171","msg":"trace[73332054] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"270.391187ms","start":"2026-04-04T10:15:43.647743Z","end":"2026-04-04T10:15:43.918134Z","steps":["trace[73332054] 'process raft request' (duration: 251.488731ms)","trace[73332054] 'compare' (duration: 18.413791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:15:43.918209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.872962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:15:43.918248Z","caller":"traceutil/trace.go:171","msg":"trace[1765430167] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2562; }","duration":"254.932473ms","start":"2026-04-04T10:15:43.663305Z","end":"2026-04-04T10:15:43.918238Z","steps":["trace[1765430167] 'agreement among raft nodes before linearized reading' (duration: 254.831701ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:16:16.449856Z","caller":"traceutil/trace.go:171","msg":"trace[1688548992] linearizableReadLoop","detail":"{readStateIndex:2840; appliedIndex:2839; }","duration":"120.10577ms","start":"2026-04-04T10:16:16.329727Z","end":"2026-04-04T10:16:16.449833Z","steps":["trace[1688548992] 'read index received' (duration: 119.881076ms)","trace[1688548992] 'applied index is now lower than readState.Index' (duration: 223.834µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:16:16.449937Z","caller":"traceutil/trace.go:171","msg":"trace[2082331954] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"124.656243ms","start":"2026-04-04T10:16:16.325259Z","end":"2026-04-04T10:16:16.449915Z","steps":["trace[2082331954] 'process raft request' (duration: 124.408419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:16:16.450079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.328423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-04T10:16:16.450112Z","caller":"traceutil/trace.go:171","msg":"trace[1887740512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2744; }","duration":"120.412714ms","start":"2026-04-04T10:16:16.329689Z","end":"2026-04-04T10:16:16.450102Z","steps":["trace[1887740512] 'agreement among raft nodes before linearized reading' (duration: 120.281072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:16:16.450241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.348003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-04T10:16:16.450275Z","caller":"traceutil/trace.go:171","msg":"trace[854448574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2744; }","duration":"120.384344ms","start":"2026-04-04T10:16:16.329881Z","end":"2026-04-04T10:16:16.450265Z","steps":["trace[854448574] 'agreement among raft nodes before linearized reading' (duration: 120.264982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:16:16.450506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.900386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-04T10:16:16.450565Z","caller":"traceutil/trace.go:171","msg":"trace[1423695145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2744; }","duration":"119.956587ms","start":"2026-04-04T10:16:16.330577Z","end":"2026-04-04T10:16:16.450534Z","steps":["trace[1423695145] 'agreement among raft nodes before linearized reading' (duration: 119.816175ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:16:16.795411Z","caller":"traceutil/trace.go:171","msg":"trace[202033523] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"105.16731ms","start":"2026-04-04T10:16:16.690213Z","end":"2026-04-04T10:16:16.79538Z","steps":["trace[202033523] 'process raft request' (duration: 105.101129ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:20:07.452281Z","caller":"traceutil/trace.go:171","msg":"trace[362022499] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"122.839649ms","start":"2026-04-04T10:20:07.329402Z","end":"2026-04-04T10:20:07.452242Z","steps":["trace[362022499] 'process raft request' (duration: 122.549951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:20:07.619927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.038285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:20:07.620058Z","caller":"traceutil/trace.go:171","msg":"trace[1264926477] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"159.352638ms","start":"2026-04-04T10:20:07.460688Z","end":"2026-04-04T10:20:07.620041Z","steps":["trace[1264926477] 'process raft request' (duration: 18.641125ms)","trace[1264926477] 'compare' (duration: 139.88382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:20:08.059861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.331027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-04T10:20:08.05996Z","caller":"traceutil/trace.go:171","msg":"trace[1316262548] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:4698; }","duration":"134.486302ms","start":"2026-04-04T10:20:07.925451Z","end":"2026-04-04T10:20:08.059937Z","steps":["trace[1316262548] 'range keys from in-memory index tree' (duration: 134.154843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:20:21.380724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.96012ms","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-04-04T10:20:21.380818Z","caller":"traceutil/trace.go:171","msg":"trace[327213279] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4939; }","duration":"104.111975ms","start":"2026-04-04T10:20:21.276687Z","end":"2026-04-04T10:20:21.380799Z","steps":["trace[327213279] 'range keys from in-memory index tree' (duration: 103.780026ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:21:55.10178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2950} {"level":"info","ts":"2026-04-04T10:21:55.195899Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2950,"took":"92.441827ms","hash":34230162,"current-db-size-bytes":53403648,"current-db-size":"53 MB","current-db-size-in-use-bytes":29548544,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-04T10:21:55.195969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":34230162,"revision":2950,"compact-revision":-1} {"level":"warn","ts":"2026-04-04T10:24:24.751319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.13274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-04T10:24:24.751435Z","caller":"traceutil/trace.go:171","msg":"trace[128933675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6701; }","duration":"174.308843ms","start":"2026-04-04T10:24:24.577103Z","end":"2026-04-04T10:24:24.751412Z","steps":["trace[128933675] 'range keys from in-memory index tree' (duration: 173.905458ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:24:24.751942Z","caller":"traceutil/trace.go:171","msg":"trace[1725609569] linearizableReadLoop","detail":"{readStateIndex:6942; appliedIndex:6941; }","duration":"103.425058ms","start":"2026-04-04T10:24:24.64848Z","end":"2026-04-04T10:24:24.751905Z","steps":["trace[1725609569] 'read index received' (duration: 28.524433ms)","trace[1725609569] 'applied index is now lower than readState.Index' (duration: 74.898735ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:24:24.752163Z","caller":"traceutil/trace.go:171","msg":"trace[262432925] transaction","detail":"{read_only:false; response_revision:6702; number_of_response:1; }","duration":"145.986231ms","start":"2026-04-04T10:24:24.606149Z","end":"2026-04-04T10:24:24.752135Z","steps":["trace[262432925] 'process raft request' (duration: 70.956774ms)","trace[262432925] 'compare' (duration: 74.562681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:24:24.752846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.698541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:24:24.752903Z","caller":"traceutil/trace.go:171","msg":"trace[1038806494] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:6702; }","duration":"104.4299ms","start":"2026-04-04T10:24:24.648459Z","end":"2026-04-04T10:24:24.752889Z","steps":["trace[1038806494] 'agreement among raft nodes before linearized reading' (duration: 103.558319ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:24:25.29598Z","caller":"traceutil/trace.go:171","msg":"trace[148862643] transaction","detail":"{read_only:false; response_revision:6704; number_of_response:1; }","duration":"144.190995ms","start":"2026-04-04T10:24:25.15176Z","end":"2026-04-04T10:24:25.295951Z","steps":["trace[148862643] 'process raft request' (duration: 143.703069ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:26:55.109236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5687} {"level":"info","ts":"2026-04-04T10:26:55.188656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5687,"took":"77.294871ms","hash":437695345,"current-db-size-bytes":53403648,"current-db-size":"53 MB","current-db-size-in-use-bytes":18825216,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-04T10:26:55.188881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":437695345,"revision":5687,"compact-revision":2950}