{"level":"warn","ts":"2026-04-18T09:09:03.883962Z","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-18T09:09:03.884078Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69: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.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69: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-18T09:09:03.884182Z","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-18T09:09:03.884198Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-18T09:09:03.884214Z","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-18T09:09:03.885029Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-04-18T09:09:03.885134Z","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":16,"max-cpu-available":16,"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.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69: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-18T09:09:03.890238Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.878208ms"} {"level":"info","ts":"2026-04-18T09:09:03.90203Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-04-18T09:09:03.902199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T09:09:03.90224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-04-18T09:09:03.902264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T09:09:03.902308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-04-18T09:09:03.902373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-04-18T09:09:03.911925Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T09:09:03.913916Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T09:09:03.915553Z","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-18T09:09:03.921163Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T09:09:03.921362Z","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-18T09:09:03.921503Z","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-18T09:09:03.921521Z","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-18T09:09:03.921484Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T09:09:03.923969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-04-18T09:09:03.924254Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-18T09:09:03.926152Z","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-18T09:09:03.926303Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-18T09:09:03.926338Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-18T09:09:03.926405Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T09:09:03.926442Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T09:09:04.703409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T09:09:04.703483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T09:09:04.703529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-04-18T09:09:04.703547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-04-18T09:09:04.703554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-18T09:09:04.703565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-04-18T09:09:04.703576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-18T09:09:04.70486Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T09:09:04.705028Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:09:04.705054Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:09:04.7052Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:09:04.706486Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:09:04.706615Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:09:04.706657Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:09:04.707561Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T09:09:04.707661Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T09:09:04.707717Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"info","ts":"2026-04-18T09:09:04.707841Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T09:09:45.391652Z","caller":"traceutil/trace.go:171","msg":"trace[1541873159] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"293.743768ms","start":"2026-04-18T09:09:45.097784Z","end":"2026-04-18T09:09:45.391527Z","steps":["trace[1541873159] 'process raft request' (duration: 288.050181ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:09:45.391648Z","caller":"traceutil/trace.go:171","msg":"trace[141908692] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"167.81581ms","start":"2026-04-18T09:09:45.223697Z","end":"2026-04-18T09:09:45.391512Z","steps":["trace[141908692] 'read index received' (duration: 160.790771ms)","trace[141908692] 'applied index is now lower than readState.Index' (duration: 7.022269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:09:45.391952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.114726ms","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-18T09:09:45.392081Z","caller":"traceutil/trace.go:171","msg":"trace[2130215402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"168.397504ms","start":"2026-04-18T09:09:45.22365Z","end":"2026-04-18T09:09:45.392047Z","steps":["trace[2130215402] 'agreement among raft nodes before linearized reading' (duration: 167.973083ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:09:45.856965Z","caller":"traceutil/trace.go:171","msg":"trace[1070641224] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"135.557171ms","start":"2026-04-18T09:09:45.72137Z","end":"2026-04-18T09:09:45.856927Z","steps":["trace[1070641224] 'read index received' (duration: 135.274954ms)","trace[1070641224] 'applied index is now lower than readState.Index' (duration: 280.987µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:09:45.857012Z","caller":"traceutil/trace.go:171","msg":"trace[953009907] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"388.025782ms","start":"2026-04-18T09:09:45.468965Z","end":"2026-04-18T09:09:45.856991Z","steps":["trace[953009907] 'process raft request' (duration: 387.792477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:09:45.857224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.838239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:09:45.85734Z","caller":"traceutil/trace.go:171","msg":"trace[650334372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"136.018173ms","start":"2026-04-18T09:09:45.721295Z","end":"2026-04-18T09:09:45.857314Z","steps":["trace[650334372] 'agreement among raft nodes before linearized reading' (duration: 135.748615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:09:45.85748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:09:45.468934Z","time spent":"388.130416ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:10:02.131447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.745279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-99rrm\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-18T09:10:02.131558Z","caller":"traceutil/trace.go:171","msg":"trace[288961886] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-99rrm; range_end:; response_count:1; response_revision:753; }","duration":"240.932944ms","start":"2026-04-18T09:10:01.8906Z","end":"2026-04-18T09:10:02.131533Z","steps":["trace[288961886] 'range keys from in-memory index tree' (duration: 240.501003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:10:56.687625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.74201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-18T09:10:56.687767Z","caller":"traceutil/trace.go:171","msg":"trace[139982067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1422; }","duration":"163.981405ms","start":"2026-04-18T09:10:56.523755Z","end":"2026-04-18T09:10:56.687737Z","steps":["trace[139982067] 'range keys from in-memory index tree' (duration: 163.396052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:11:20.750734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.139266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:11:20.751005Z","caller":"traceutil/trace.go:171","msg":"trace[1314211155] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"276.91857ms","start":"2026-04-18T09:11:20.474035Z","end":"2026-04-18T09:11:20.750953Z","steps":["trace[1314211155] 'process raft request' (duration: 163.75366ms)","trace[1314211155] 'compare' (duration: 111.977131ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:11:20.751383Z","caller":"traceutil/trace.go:171","msg":"trace[712135486] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"274.250516ms","start":"2026-04-18T09:11:20.477118Z","end":"2026-04-18T09:11:20.751369Z","steps":["trace[712135486] 'process raft request' (duration: 273.804615ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:11:20.75154Z","caller":"traceutil/trace.go:171","msg":"trace[933102026] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"274.40291ms","start":"2026-04-18T09:11:20.477074Z","end":"2026-04-18T09:11:20.751477Z","steps":["trace[933102026] 'process raft request' (duration: 273.908158ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:11:20.75166Z","caller":"traceutil/trace.go:171","msg":"trace[811532150] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"274.674255ms","start":"2026-04-18T09:11:20.476964Z","end":"2026-04-18T09:11:20.751638Z","steps":["trace[811532150] 'process raft request' (duration: 273.894676ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:10.774074Z","caller":"traceutil/trace.go:171","msg":"trace[490291289] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"111.350498ms","start":"2026-04-18T09:12:10.662693Z","end":"2026-04-18T09:12:10.774044Z","steps":["trace[490291289] 'process raft request' (duration: 81.548501ms)","trace[490291289] 'compare' (duration: 29.06476ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:16.58886Z","caller":"traceutil/trace.go:171","msg":"trace[1022391873] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"177.962851ms","start":"2026-04-18T09:12:16.410854Z","end":"2026-04-18T09:12:16.588816Z","steps":["trace[1022391873] 'process raft request' (duration: 113.732045ms)","trace[1022391873] 'compare' (duration: 63.98572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:13:37.821803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.951182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:13:37.82211Z","caller":"traceutil/trace.go:171","msg":"trace[282026417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2821; }","duration":"101.333141ms","start":"2026-04-18T09:13:37.720751Z","end":"2026-04-18T09:13:37.822084Z","steps":["trace[282026417] 'range keys from in-memory index tree' (duration: 100.776178ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:38.134727Z","caller":"traceutil/trace.go:171","msg":"trace[37019897] linearizableReadLoop","detail":"{readStateIndex:2936; appliedIndex:2934; }","duration":"170.99767ms","start":"2026-04-18T09:13:37.963704Z","end":"2026-04-18T09:13:38.134702Z","steps":["trace[37019897] 'read index received' (duration: 104.509498ms)","trace[37019897] 'applied index is now lower than readState.Index' (duration: 66.486772ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:13:38.134828Z","caller":"traceutil/trace.go:171","msg":"trace[688791082] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"197.267703ms","start":"2026-04-18T09:13:37.93747Z","end":"2026-04-18T09:13:38.134738Z","steps":["trace[688791082] 'process raft request' (duration: 130.73538ms)","trace[688791082] 'compare' (duration: 66.264817ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:13:38.13503Z","caller":"traceutil/trace.go:171","msg":"trace[1936201754] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"183.03648ms","start":"2026-04-18T09:13:37.951961Z","end":"2026-04-18T09:13:38.134998Z","steps":["trace[1936201754] 'process raft request' (duration: 182.680152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:13:38.135142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.42101ms","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-18T09:13:38.135256Z","caller":"traceutil/trace.go:171","msg":"trace[980916649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2823; }","duration":"171.522342ms","start":"2026-04-18T09:13:37.963645Z","end":"2026-04-18T09:13:38.135168Z","steps":["trace[980916649] 'agreement among raft nodes before linearized reading' (duration: 171.159083ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:16:13.524519Z","caller":"traceutil/trace.go:171","msg":"trace[1816478240] linearizableReadLoop","detail":"{readStateIndex:3996; appliedIndex:3995; }","duration":"126.360275ms","start":"2026-04-18T09:16:13.398133Z","end":"2026-04-18T09:16:13.524493Z","steps":["trace[1816478240] 'read index received' (duration: 126.196161ms)","trace[1816478240] 'applied index is now lower than readState.Index' (duration: 163.304µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:16:13.524877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.734405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:15815"} {"level":"info","ts":"2026-04-18T09:16:13.524933Z","caller":"traceutil/trace.go:171","msg":"trace[264534779] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:3845; }","duration":"126.861227ms","start":"2026-04-18T09:16:13.398056Z","end":"2026-04-18T09:16:13.524917Z","steps":["trace[264534779] 'agreement among raft nodes before linearized reading' (duration: 126.660822ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:16:13.52516Z","caller":"traceutil/trace.go:171","msg":"trace[500288564] transaction","detail":"{read_only:false; response_revision:3845; number_of_response:1; }","duration":"128.683361ms","start":"2026-04-18T09:16:13.39646Z","end":"2026-04-18T09:16:13.525143Z","steps":["trace[500288564] 'process raft request' (duration: 127.881262ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:17:35.411569Z","caller":"traceutil/trace.go:171","msg":"trace[863955988] linearizableReadLoop","detail":"{readStateIndex:4959; appliedIndex:4957; }","duration":"102.442785ms","start":"2026-04-18T09:17:35.309094Z","end":"2026-04-18T09:17:35.411537Z","steps":["trace[863955988] 'read index received' (duration: 72.553785ms)","trace[863955988] 'applied index is now lower than readState.Index' (duration: 29.88782ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:17:35.411655Z","caller":"traceutil/trace.go:171","msg":"trace[625851616] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"103.378538ms","start":"2026-04-18T09:17:35.308221Z","end":"2026-04-18T09:17:35.411599Z","steps":["trace[625851616] 'process raft request' (duration: 103.158093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:17:35.411892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.769872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-18T09:17:35.411929Z","caller":"traceutil/trace.go:171","msg":"trace[789973872] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4771; }","duration":"102.849524ms","start":"2026-04-18T09:17:35.309072Z","end":"2026-04-18T09:17:35.411921Z","steps":["trace[789973872] 'agreement among raft nodes before linearized reading' (duration: 102.626059ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:17:45.06252Z","caller":"traceutil/trace.go:171","msg":"trace[2070481696] linearizableReadLoop","detail":"{readStateIndex:5262; appliedIndex:5261; }","duration":"273.771899ms","start":"2026-04-18T09:17:44.788725Z","end":"2026-04-18T09:17:45.062497Z","steps":["trace[2070481696] 'read index received' (duration: 273.591944ms)","trace[2070481696] 'applied index is now lower than readState.Index' (duration: 179.315µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:17:45.062611Z","caller":"traceutil/trace.go:171","msg":"trace[2124673146] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"278.03607ms","start":"2026-04-18T09:17:44.784569Z","end":"2026-04-18T09:17:45.062605Z","steps":["trace[2124673146] 'process raft request' (duration: 277.804805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:17:45.06303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.751239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:17:45.063156Z","caller":"traceutil/trace.go:171","msg":"trace[822378113] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:5065; }","duration":"230.004216ms","start":"2026-04-18T09:17:44.833125Z","end":"2026-04-18T09:17:45.063129Z","steps":["trace[822378113] 'agreement among raft nodes before linearized reading' (duration: 229.723569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:17:45.063169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.412294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/vector\" ","response":"range_response_count:1 size:5083"} {"level":"info","ts":"2026-04-18T09:17:45.063292Z","caller":"traceutil/trace.go:171","msg":"trace[108097049] range","detail":"{range_begin:/registry/daemonsets/monitoring/vector; range_end:; response_count:1; response_revision:5065; }","duration":"274.561038ms","start":"2026-04-18T09:17:44.788695Z","end":"2026-04-18T09:17:45.063257Z","steps":["trace[108097049] 'agreement among raft nodes before linearized reading' (duration: 274.209999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:17:45.063057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.735766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-04-18T09:17:45.063395Z","caller":"traceutil/trace.go:171","msg":"trace[1697243096] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5065; }","duration":"222.112277ms","start":"2026-04-18T09:17:44.841264Z","end":"2026-04-18T09:17:45.063377Z","steps":["trace[1697243096] 'agreement among raft nodes before linearized reading' (duration: 221.666055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:17:45.06344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.459595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0b3a2fa7-4cd0-4dc2-91bf-d735eba3b58c\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-18T09:17:45.06358Z","caller":"traceutil/trace.go:171","msg":"trace[1881778485] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0b3a2fa7-4cd0-4dc2-91bf-d735eba3b58c; range_end:; response_count:1; response_revision:5065; }","duration":"227.66567ms","start":"2026-04-18T09:17:44.835878Z","end":"2026-04-18T09:17:45.063544Z","steps":["trace[1881778485] 'agreement among raft nodes before linearized reading' (duration: 227.241008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:17:56.589328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.583755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-18T09:17:56.589421Z","caller":"traceutil/trace.go:171","msg":"trace[1524893712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5278; }","duration":"133.721689ms","start":"2026-04-18T09:17:56.455681Z","end":"2026-04-18T09:17:56.589403Z","steps":["trace[1524893712] 'range keys from in-memory index tree' (duration: 133.421131ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:18:04.836747Z","caller":"traceutil/trace.go:171","msg":"trace[1868474316] linearizableReadLoop","detail":"{readStateIndex:5679; appliedIndex:5678; }","duration":"135.907721ms","start":"2026-04-18T09:18:04.700805Z","end":"2026-04-18T09:18:04.836713Z","steps":["trace[1868474316] 'read index received' (duration: 132.215793ms)","trace[1868474316] 'applied index is now lower than readState.Index' (duration: 3.690518ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:18:04.836855Z","caller":"traceutil/trace.go:171","msg":"trace[1368503222] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"182.620775ms","start":"2026-04-18T09:18:04.654165Z","end":"2026-04-18T09:18:04.836785Z","steps":["trace[1368503222] 'process raft request' (duration: 178.994018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:18:04.836997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.157685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:18:04.837072Z","caller":"traceutil/trace.go:171","msg":"trace[778602672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5472; }","duration":"116.290258ms","start":"2026-04-18T09:18:04.720763Z","end":"2026-04-18T09:18:04.837053Z","steps":["trace[778602672] 'agreement among raft nodes before linearized reading' (duration: 116.155825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:18:04.837071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.176078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-18T09:18:04.838597Z","caller":"traceutil/trace.go:171","msg":"trace[767035384] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5472; }","duration":"137.797988ms","start":"2026-04-18T09:18:04.700741Z","end":"2026-04-18T09:18:04.838539Z","steps":["trace[767035384] 'agreement among raft nodes before linearized reading' (duration: 136.086816ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:18:05.037446Z","caller":"traceutil/trace.go:171","msg":"trace[1807893446] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"166.264341ms","start":"2026-04-18T09:18:04.871144Z","end":"2026-04-18T09:18:05.037409Z","steps":["trace[1807893446] 'process raft request' (duration: 120.863198ms)","trace[1807893446] 'compare' (duration: 45.231869ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:18:05.052676Z","caller":"traceutil/trace.go:171","msg":"trace[94379375] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"180.962565ms","start":"2026-04-18T09:18:04.871694Z","end":"2026-04-18T09:18:05.052656Z","steps":["trace[94379375] 'process raft request' (duration: 180.680268ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:18:05.052695Z","caller":"traceutil/trace.go:171","msg":"trace[985326290] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"175.895943ms","start":"2026-04-18T09:18:04.876757Z","end":"2026-04-18T09:18:05.052653Z","steps":["trace[985326290] 'process raft request' (duration: 175.824601ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:19:04.733678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2971} {"level":"info","ts":"2026-04-18T09:19:04.811668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2971,"took":"76.55413ms","hash":814667770,"current-db-size-bytes":57860096,"current-db-size":"58 MB","current-db-size-in-use-bytes":33464320,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-18T09:19:04.811756Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":814667770,"revision":2971,"compact-revision":-1} {"level":"info","ts":"2026-04-18T09:24:04.787288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6028} {"level":"info","ts":"2026-04-18T09:24:04.985061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6028,"took":"194.746373ms","hash":441835933,"current-db-size-bytes":57860096,"current-db-size":"58 MB","current-db-size-in-use-bytes":21610496,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-18T09:24:04.985126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":441835933,"revision":6028,"compact-revision":2971} {"level":"info","ts":"2026-04-18T09:24:11.783209Z","caller":"traceutil/trace.go:171","msg":"trace[1395341128] linearizableReadLoop","detail":"{readStateIndex:8584; appliedIndex:8583; }","duration":"219.690443ms","start":"2026-04-18T09:24:11.56349Z","end":"2026-04-18T09:24:11.78318Z","steps":["trace[1395341128] 'read index received' (duration: 219.613481ms)","trace[1395341128] 'applied index is now lower than readState.Index' (duration: 76.192µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:24:11.783356Z","caller":"traceutil/trace.go:171","msg":"trace[1839678438] transaction","detail":"{read_only:false; response_revision:8290; number_of_response:1; }","duration":"221.26498ms","start":"2026-04-18T09:24:11.562049Z","end":"2026-04-18T09:24:11.783314Z","steps":["trace[1839678438] 'process raft request' (duration: 220.990253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:24:11.783421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.919769ms","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-18T09:24:11.783445Z","caller":"traceutil/trace.go:171","msg":"trace[1035644716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8290; }","duration":"220.00197ms","start":"2026-04-18T09:24:11.563438Z","end":"2026-04-18T09:24:11.78344Z","steps":["trace[1035644716] 'agreement among raft nodes before linearized reading' (duration: 219.890287ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:24:11.957976Z","caller":"traceutil/trace.go:171","msg":"trace[201902094] transaction","detail":"{read_only:false; response_revision:8291; number_of_response:1; }","duration":"170.181795ms","start":"2026-04-18T09:24:11.787765Z","end":"2026-04-18T09:24:11.957947Z","steps":["trace[201902094] 'process raft request' (duration: 169.97577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:24:23.553662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.263066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-18T09:24:23.553754Z","caller":"traceutil/trace.go:171","msg":"trace[2065791123] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8351; }","duration":"260.383939ms","start":"2026-04-18T09:24:23.293348Z","end":"2026-04-18T09:24:23.553732Z","steps":["trace[2065791123] 'range keys from in-memory index tree' (duration: 260.061531ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:24:24.303935Z","caller":"traceutil/trace.go:171","msg":"trace[637353519] linearizableReadLoop","detail":"{readStateIndex:8651; appliedIndex:8650; }","duration":"281.030294ms","start":"2026-04-18T09:24:24.022877Z","end":"2026-04-18T09:24:24.303907Z","steps":["trace[637353519] 'read index received' (duration: 280.808409ms)","trace[637353519] 'applied index is now lower than readState.Index' (duration: 220.555µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:24:24.304254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.367172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ngmqs\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-18T09:24:24.304333Z","caller":"traceutil/trace.go:171","msg":"trace[415906999] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ngmqs; range_end:; response_count:1; response_revision:8353; }","duration":"281.493875ms","start":"2026-04-18T09:24:24.022821Z","end":"2026-04-18T09:24:24.304315Z","steps":["trace[415906999] 'agreement among raft nodes before linearized reading' (duration: 281.238899ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:24:24.304212Z","caller":"traceutil/trace.go:171","msg":"trace[1256080449] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"288.637877ms","start":"2026-04-18T09:24:24.015494Z","end":"2026-04-18T09:24:24.304132Z","steps":["trace[1256080449] 'process raft request' (duration: 288.224247ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:24:52.837378Z","caller":"traceutil/trace.go:171","msg":"trace[707649664] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"361.372483ms","start":"2026-04-18T09:24:52.475969Z","end":"2026-04-18T09:24:52.837342Z","steps":["trace[707649664] 'process raft request' (duration: 306.563097ms)","trace[707649664] 'compare' (duration: 54.527629ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:24:52.837398Z","caller":"traceutil/trace.go:171","msg":"trace[1501883005] linearizableReadLoop","detail":"{readStateIndex:8865; appliedIndex:8864; }","duration":"326.763883ms","start":"2026-04-18T09:24:52.510608Z","end":"2026-04-18T09:24:52.837372Z","steps":["trace[1501883005] 'read index received' (duration: 272.08297ms)","trace[1501883005] 'applied index is now lower than readState.Index' (duration: 54.679483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:24:52.837576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:24:52.475958Z","time spent":"361.520856ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:24:52.837859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.173362ms","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-04-18T09:24:52.837962Z","caller":"traceutil/trace.go:171","msg":"trace[1924265051] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8561; }","duration":"327.363816ms","start":"2026-04-18T09:24:52.510574Z","end":"2026-04-18T09:24:52.837938Z","steps":["trace[1924265051] 'agreement among raft nodes before linearized reading' (duration: 326.905485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:24:52.838102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:24:52.510561Z","time spent":"327.524851ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-18T09:24:52.92126Z","caller":"traceutil/trace.go:171","msg":"trace[703318239] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"112.822198ms","start":"2026-04-18T09:24:52.808415Z","end":"2026-04-18T09:24:52.921238Z","steps":["trace[703318239] 'process raft request' (duration: 112.719805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:24:52.922544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.685408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ngmqs\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-18T09:24:52.92265Z","caller":"traceutil/trace.go:171","msg":"trace[998781046] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ngmqs; range_end:; response_count:1; response_revision:8562; }","duration":"393.831992ms","start":"2026-04-18T09:24:52.528802Z","end":"2026-04-18T09:24:52.922634Z","steps":["trace[998781046] 'agreement among raft nodes before linearized reading' (duration: 393.573636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:24:52.92272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:24:52.528792Z","time spent":"393.915283ms","remote":"127.0.0.1:34884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-ngmqs\" "} {"level":"info","ts":"2026-04-18T09:24:52.946793Z","caller":"traceutil/trace.go:171","msg":"trace[915299461] transaction","detail":"{read_only:false; response_revision:8563; number_of_response:1; }","duration":"108.142675ms","start":"2026-04-18T09:24:52.838628Z","end":"2026-04-18T09:24:52.946771Z","steps":["trace[915299461] 'process raft request' (duration: 107.871319ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:24:57.076504Z","caller":"traceutil/trace.go:171","msg":"trace[1387987635] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"248.639638ms","start":"2026-04-18T09:24:56.827823Z","end":"2026-04-18T09:24:57.076462Z","steps":["trace[1387987635] 'process raft request' (duration: 219.792346ms)","trace[1387987635] 'compare' (duration: 28.599316ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:24:57.076499Z","caller":"traceutil/trace.go:171","msg":"trace[566432491] linearizableReadLoop","detail":"{readStateIndex:8886; appliedIndex:8885; }","duration":"222.421058ms","start":"2026-04-18T09:24:56.854043Z","end":"2026-04-18T09:24:57.076464Z","steps":["trace[566432491] 'read index received' (duration: 193.593616ms)","trace[566432491] 'applied index is now lower than readState.Index' (duration: 28.825842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:24:57.076761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.677194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:24:57.076871Z","caller":"traceutil/trace.go:171","msg":"trace[720122212] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8581; }","duration":"222.864628ms","start":"2026-04-18T09:24:56.85398Z","end":"2026-04-18T09:24:57.076844Z","steps":["trace[720122212] 'agreement among raft nodes before linearized reading' (duration: 222.593101ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:24:57.076897Z","caller":"traceutil/trace.go:171","msg":"trace[1691451541] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"222.774376ms","start":"2026-04-18T09:24:56.854112Z","end":"2026-04-18T09:24:57.076887Z","steps":["trace[1691451541] 'process raft request' (duration: 222.254664ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:24:57.077006Z","caller":"traceutil/trace.go:171","msg":"trace[508364827] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"143.290959ms","start":"2026-04-18T09:24:56.933693Z","end":"2026-04-18T09:24:57.076984Z","steps":["trace[508364827] 'process raft request' (duration: 143.047293ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:24:57.07702Z","caller":"traceutil/trace.go:171","msg":"trace[1107327079] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"116.225689ms","start":"2026-04-18T09:24:56.960764Z","end":"2026-04-18T09:24:57.07699Z","steps":["trace[1107327079] 'process raft request' (duration: 116.151568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:24:57.077208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.109324ms","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":"warn","ts":"2026-04-18T09:24:57.077209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.890533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:24:57.077331Z","caller":"traceutil/trace.go:171","msg":"trace[1481164521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8583; }","duration":"223.302579ms","start":"2026-04-18T09:24:56.854004Z","end":"2026-04-18T09:24:57.077307Z","steps":["trace[1481164521] 'agreement among raft nodes before linearized reading' (duration: 222.978621ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:24:57.077394Z","caller":"traceutil/trace.go:171","msg":"trace[945927725] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8583; }","duration":"219.131549ms","start":"2026-04-18T09:24:56.858217Z","end":"2026-04-18T09:24:57.077349Z","steps":["trace[945927725] 'agreement among raft nodes before linearized reading' (duration: 218.902173ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:25:05.596619Z","caller":"traceutil/trace.go:171","msg":"trace[1571109596] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"135.910711ms","start":"2026-04-18T09:25:05.46064Z","end":"2026-04-18T09:25:05.59655Z","steps":["trace[1571109596] 'process raft request' (duration: 109.213091ms)","trace[1571109596] 'compare' (duration: 26.406983ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:25:05.827607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.088959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-18T09:25:05.827741Z","caller":"traceutil/trace.go:171","msg":"trace[1978639631] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8671; }","duration":"119.468018ms","start":"2026-04-18T09:25:05.708246Z","end":"2026-04-18T09:25:05.827714Z","steps":["trace[1978639631] 'range keys from in-memory index tree' (duration: 118.593797ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:25:06.701746Z","caller":"traceutil/trace.go:171","msg":"trace[1310183225] linearizableReadLoop","detail":"{readStateIndex:8987; appliedIndex:8986; }","duration":"205.138553ms","start":"2026-04-18T09:25:06.496554Z","end":"2026-04-18T09:25:06.701693Z","steps":["trace[1310183225] 'read index received' (duration: 188.947054ms)","trace[1310183225] 'applied index is now lower than readState.Index' (duration: 16.189769ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:25:06.701761Z","caller":"traceutil/trace.go:171","msg":"trace[2069069609] transaction","detail":"{read_only:false; response_revision:8676; number_of_response:1; }","duration":"237.254155ms","start":"2026-04-18T09:25:06.464469Z","end":"2026-04-18T09:25:06.701723Z","steps":["trace[2069069609] 'process raft request' (duration: 220.993284ms)","trace[2069069609] 'compare' (duration: 15.944583ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:25:06.702024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.43653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:25:06.702114Z","caller":"traceutil/trace.go:171","msg":"trace[946107690] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8676; }","duration":"205.567564ms","start":"2026-04-18T09:25:06.496525Z","end":"2026-04-18T09:25:06.702092Z","steps":["trace[946107690] 'agreement among raft nodes before linearized reading' (duration: 205.344148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:25:06.70227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.967559ms","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-18T09:25:06.702316Z","caller":"traceutil/trace.go:171","msg":"trace[960833612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8676; }","duration":"170.172795ms","start":"2026-04-18T09:25:06.53213Z","end":"2026-04-18T09:25:06.702303Z","steps":["trace[960833612] 'agreement among raft nodes before linearized reading' (duration: 169.756445ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:25:24.514406Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ed.wal"} {"level":"info","ts":"2026-04-18T09:26:19.546208Z","caller":"traceutil/trace.go:171","msg":"trace[855161996] transaction","detail":"{read_only:false; response_revision:9406; number_of_response:1; }","duration":"148.819344ms","start":"2026-04-18T09:26:19.39736Z","end":"2026-04-18T09:26:19.546179Z","steps":["trace[855161996] 'process raft request' (duration: 85.437092ms)","trace[855161996] 'compare' (duration: 63.112396ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:26:19.546507Z","caller":"traceutil/trace.go:171","msg":"trace[362941316] transaction","detail":"{read_only:false; response_revision:9407; number_of_response:1; }","duration":"148.923356ms","start":"2026-04-18T09:26:19.397551Z","end":"2026-04-18T09:26:19.546474Z","steps":["trace[362941316] 'process raft request' (duration: 148.578138ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:26:19.546619Z","caller":"traceutil/trace.go:171","msg":"trace[1130311721] transaction","detail":"{read_only:false; response_revision:9408; number_of_response:1; }","duration":"147.878531ms","start":"2026-04-18T09:26:19.398677Z","end":"2026-04-18T09:26:19.546555Z","steps":["trace[1130311721] 'process raft request' (duration: 147.719237ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:26:19.669452Z","caller":"traceutil/trace.go:171","msg":"trace[1457445702] transaction","detail":"{read_only:false; response_revision:9410; number_of_response:1; }","duration":"117.883941ms","start":"2026-04-18T09:26:19.551528Z","end":"2026-04-18T09:26:19.669412Z","steps":["trace[1457445702] 'process raft request' (duration: 97.398609ms)","trace[1457445702] 'compare' (duration: 20.337088ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:26:54.454321Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fe334179879fd0aa","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-18T09:26:54.460359Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-18T09:26:54.460515Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-18T09:28:34.543718Z","caller":"traceutil/trace.go:171","msg":"trace[1887505690] transaction","detail":"{read_only:false; response_revision:10441; number_of_response:1; }","duration":"119.345088ms","start":"2026-04-18T09:28:34.424344Z","end":"2026-04-18T09:28:34.543689Z","steps":["trace[1887505690] 'process raft request' (duration: 119.136873ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:29:04.792943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8117} {"level":"info","ts":"2026-04-18T09:29:04.851506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8117,"took":"56.653811ms","hash":830554172,"current-db-size-bytes":57860096,"current-db-size":"58 MB","current-db-size-in-use-bytes":29003776,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-18T09:29:04.851572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":830554172,"revision":8117,"compact-revision":6028} {"level":"info","ts":"2026-04-18T09:34:04.799602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10650} {"level":"info","ts":"2026-04-18T09:34:04.88281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10650,"took":"81.183055ms","hash":4040301124,"current-db-size-bytes":57860096,"current-db-size":"58 MB","current-db-size-in-use-bytes":21311488,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-18T09:34:04.882885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4040301124,"revision":10650,"compact-revision":8117}