{"level":"warn","ts":"2026-04-10T13:45:34.727645Z","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-10T13:45:34.727765Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.228:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.228:2380","--initial-cluster=instance=https://199.19.213.228:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.228:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.228: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-10T13:45:34.728227Z","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-10T13:45:34.728244Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-04-10T13:45:34.728265Z","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-10T13:45:34.72896Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"]} {"level":"info","ts":"2026-04-10T13:45:34.729109Z","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.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.228: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-10T13:45:34.733461Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.021721ms"} {"level":"info","ts":"2026-04-10T13:45:34.745511Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db79120667ce701f","cluster-id":"793c581eb68f3646"} {"level":"info","ts":"2026-04-10T13:45:34.745624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=()"} {"level":"info","ts":"2026-04-10T13:45:34.745645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 0"} {"level":"info","ts":"2026-04-10T13:45:34.7458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db79120667ce701f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-10T13:45:34.745819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 1"} {"level":"info","ts":"2026-04-10T13:45:34.745936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"warn","ts":"2026-04-10T13:45:34.751441Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-10T13:45:34.752771Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-10T13:45:34.754155Z","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-10T13:45:34.757388Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db79120667ce701f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-10T13:45:34.757571Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db79120667ce701f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-10T13:45:34.757626Z","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-10T13:45:34.75785Z","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-10T13:45:34.757862Z","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-10T13:45:34.760011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"info","ts":"2026-04-10T13:45:34.760253Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","added-peer-id":"db79120667ce701f","added-peer-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-04-10T13:45:34.762339Z","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-10T13:45:34.762409Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-04-10T13:45:34.762444Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-04-10T13:45:34.762637Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db79120667ce701f","initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-10T13:45:34.762688Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-10T13:45:35.746939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f is starting a new election at term 1"} {"level":"info","ts":"2026-04-10T13:45:35.747025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-10T13:45:35.747053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgPreVoteResp from db79120667ce701f at term 1"} {"level":"info","ts":"2026-04-10T13:45:35.747077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became candidate at term 2"} {"level":"info","ts":"2026-04-10T13:45:35.74712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgVoteResp from db79120667ce701f at term 2"} {"level":"info","ts":"2026-04-10T13:45:35.747145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became leader at term 2"} {"level":"info","ts":"2026-04-10T13:45:35.747165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db79120667ce701f elected leader db79120667ce701f at term 2"} {"level":"info","ts":"2026-04-10T13:45:35.748307Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:45:35.748507Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db79120667ce701f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.228:2379]}","request-path":"/0/members/db79120667ce701f/attributes","cluster-id":"793c581eb68f3646","publish-timeout":"7s"} {"level":"info","ts":"2026-04-10T13:45:35.748709Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T13:45:35.748746Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T13:45:35.748819Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-10T13:45:35.748872Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-10T13:45:35.749414Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:45:35.749616Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:45:35.749667Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:45:35.752006Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-10T13:45:35.752122Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.228:2379"} {"level":"info","ts":"2026-04-10T13:45:38.34625Z","caller":"traceutil/trace.go:171","msg":"trace[927033007] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"119.330736ms","start":"2026-04-10T13:45:38.226877Z","end":"2026-04-10T13:45:38.346207Z","steps":["trace[927033007] 'process raft request' (duration: 113.369602ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:06.311946Z","caller":"traceutil/trace.go:171","msg":"trace[998585078] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"187.077101ms","start":"2026-04-10T13:46:06.124813Z","end":"2026-04-10T13:46:06.311891Z","steps":["trace[998585078] 'process raft request' (duration: 185.886648ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:06.321216Z","caller":"traceutil/trace.go:171","msg":"trace[57954999] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"192.372037ms","start":"2026-04-10T13:46:06.128783Z","end":"2026-04-10T13:46:06.321155Z","steps":["trace[57954999] 'process raft request' (duration: 192.107079ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:06.321351Z","caller":"traceutil/trace.go:171","msg":"trace[1446807793] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"182.916486ms","start":"2026-04-10T13:46:06.138407Z","end":"2026-04-10T13:46:06.321324Z","steps":["trace[1446807793] 'process raft request' (duration: 182.658359ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:06.321456Z","caller":"traceutil/trace.go:171","msg":"trace[956168035] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"176.198771ms","start":"2026-04-10T13:46:06.145236Z","end":"2026-04-10T13:46:06.321434Z","steps":["trace[956168035] 'process raft request' (duration: 176.024627ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:06.321553Z","caller":"traceutil/trace.go:171","msg":"trace[1922774048] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:530; }","duration":"112.747374ms","start":"2026-04-10T13:46:06.208758Z","end":"2026-04-10T13:46:06.321505Z","steps":["trace[1922774048] 'read index received' (duration: 98.07253ms)","trace[1922774048] 'applied index is now lower than readState.Index' (duration: 14.672324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:46:06.321755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.986771ms","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-10T13:46:06.321882Z","caller":"traceutil/trace.go:171","msg":"trace[1028960256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"113.152035ms","start":"2026-04-10T13:46:06.208717Z","end":"2026-04-10T13:46:06.321869Z","steps":["trace[1028960256] 'agreement among raft nodes before linearized reading' (duration: 112.891138ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:12.658306Z","caller":"traceutil/trace.go:171","msg":"trace[1788603207] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"192.390196ms","start":"2026-04-10T13:46:12.465888Z","end":"2026-04-10T13:46:12.658279Z","steps":["trace[1788603207] 'process raft request' (duration: 190.814123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:46:13.471984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.948145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:46:13.472245Z","caller":"traceutil/trace.go:171","msg":"trace[389484355] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"684.69187ms","start":"2026-04-10T13:46:12.787514Z","end":"2026-04-10T13:46:13.472206Z","steps":["trace[389484355] 'process raft request' (duration: 131.84949ms)","trace[389484355] 'compare' (duration: 551.78384ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:46:13.472298Z","caller":"traceutil/trace.go:171","msg":"trace[624679526] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:600; }","duration":"682.232582ms","start":"2026-04-10T13:46:12.790051Z","end":"2026-04-10T13:46:13.472283Z","steps":["trace[624679526] 'read index received' (duration: 129.401832ms)","trace[624679526] 'applied index is now lower than readState.Index' (duration: 552.82973ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:46:13.472299Z","caller":"traceutil/trace.go:171","msg":"trace[2098872645] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"684.72443ms","start":"2026-04-10T13:46:12.787539Z","end":"2026-04-10T13:46:13.472263Z","steps":["trace[2098872645] 'process raft request' (duration: 684.549885ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:13.472413Z","caller":"traceutil/trace.go:171","msg":"trace[183675673] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"682.983392ms","start":"2026-04-10T13:46:12.789399Z","end":"2026-04-10T13:46:13.472382Z","steps":["trace[183675673] 'process raft request' (duration: 682.831038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:46:13.472414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:46:12.787487Z","time spent":"684.873225ms","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:46:13.472429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:46:12.787456Z","time spent":"684.887884ms","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T13:46:13.472543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.477649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qwfcj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-10T13:46:13.47262Z","caller":"traceutil/trace.go:171","msg":"trace[1740889617] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qwfcj; range_end:; response_count:1; response_revision:583; }","duration":"682.593462ms","start":"2026-04-10T13:46:12.790011Z","end":"2026-04-10T13:46:13.472604Z","steps":["trace[1740889617] 'agreement among raft nodes before linearized reading' (duration: 682.377186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:46:13.472621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.191661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:46:13.472473Z","caller":"traceutil/trace.go:171","msg":"trace[80136533] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"684.051122ms","start":"2026-04-10T13:46:12.7883Z","end":"2026-04-10T13:46:13.472351Z","steps":["trace[80136533] 'process raft request' (duration: 683.863966ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:13.472682Z","caller":"traceutil/trace.go:171","msg":"trace[1994198539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"455.295754ms","start":"2026-04-10T13:46:13.017369Z","end":"2026-04-10T13:46:13.472665Z","steps":["trace[1994198539] 'agreement among raft nodes before linearized reading' (duration: 455.169861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:46:13.472675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:46:12.789993Z","time spent":"682.668454ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-qwfcj\" "} {"level":"warn","ts":"2026-04-10T13:46:13.472692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:46:12.789394Z","time spent":"683.092435ms","remote":"127.0.0.1:51406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:46:13.472716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:46:13.017347Z","time spent":"455.362086ms","remote":"127.0.0.1:51144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T13:46:13.472752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.489678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T13:46:13.472838Z","caller":"traceutil/trace.go:171","msg":"trace[49735107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"136.609481ms","start":"2026-04-10T13:46:13.336206Z","end":"2026-04-10T13:46:13.472816Z","steps":["trace[49735107] 'agreement among raft nodes before linearized reading' (duration: 136.348284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:46:13.472766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:46:12.788291Z","time spent":"684.417562ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:47:44.700483Z","caller":"traceutil/trace.go:171","msg":"trace[620891482] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"135.642644ms","start":"2026-04-10T13:47:44.564814Z","end":"2026-04-10T13:47:44.700457Z","steps":["trace[620891482] 'process raft request' (duration: 135.445558ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:47:57.126687Z","caller":"traceutil/trace.go:171","msg":"trace[2077564417] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"109.106897ms","start":"2026-04-10T13:47:57.017554Z","end":"2026-04-10T13:47:57.12666Z","steps":["trace[2077564417] 'read index received' (duration: 108.877381ms)","trace[2077564417] 'applied index is now lower than readState.Index' (duration: 228.526µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:47:57.126744Z","caller":"traceutil/trace.go:171","msg":"trace[361771525] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"122.463177ms","start":"2026-04-10T13:47:57.004251Z","end":"2026-04-10T13:47:57.126714Z","steps":["trace[361771525] 'process raft request' (duration: 122.240561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:47:57.126814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.23709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:47:57.126858Z","caller":"traceutil/trace.go:171","msg":"trace[1364982561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1916; }","duration":"109.327743ms","start":"2026-04-10T13:47:57.017521Z","end":"2026-04-10T13:47:57.126849Z","steps":["trace[1364982561] 'agreement among raft nodes before linearized reading' (duration: 109.242041ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:48:34.533396Z","caller":"traceutil/trace.go:171","msg":"trace[1619228589] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"182.933877ms","start":"2026-04-10T13:48:34.35042Z","end":"2026-04-10T13:48:34.533354Z","steps":["trace[1619228589] 'process raft request' (duration: 148.778644ms)","trace[1619228589] 'compare' (duration: 33.421143ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:48:34.533623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.803516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-10T13:48:34.533737Z","caller":"traceutil/trace.go:171","msg":"trace[1900997068] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2134; }","duration":"125.968271ms","start":"2026-04-10T13:48:34.407756Z","end":"2026-04-10T13:48:34.533724Z","steps":["trace[1900997068] 'agreement among raft nodes before linearized reading' (duration: 125.674143ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:48:34.53369Z","caller":"traceutil/trace.go:171","msg":"trace[805576426] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"163.239699ms","start":"2026-04-10T13:48:34.370371Z","end":"2026-04-10T13:48:34.533611Z","steps":["trace[805576426] 'process raft request' (duration: 162.893959ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:48:34.533424Z","caller":"traceutil/trace.go:171","msg":"trace[19587380] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2211; }","duration":"125.58115ms","start":"2026-04-10T13:48:34.407805Z","end":"2026-04-10T13:48:34.533386Z","steps":["trace[19587380] 'read index received' (duration: 91.410097ms)","trace[19587380] 'applied index is now lower than readState.Index' (duration: 34.170013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:48:34.534006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.09313ms","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-10T13:48:34.5341Z","caller":"traceutil/trace.go:171","msg":"trace[707449062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2132; }","duration":"191.217604ms","start":"2026-04-10T13:48:34.342866Z","end":"2026-04-10T13:48:34.534084Z","steps":["trace[707449062] 'range keys from in-memory index tree' (duration: 189.899597ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:48:35.014008Z","caller":"traceutil/trace.go:171","msg":"trace[1512768571] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"138.730919ms","start":"2026-04-10T13:48:34.875251Z","end":"2026-04-10T13:48:35.013982Z","steps":["trace[1512768571] 'process raft request' (duration: 138.560685ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:49:27.70924Z","caller":"traceutil/trace.go:171","msg":"trace[1135194185] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"100.848564ms","start":"2026-04-10T13:49:27.608362Z","end":"2026-04-10T13:49:27.709211Z","steps":["trace[1135194185] 'process raft request' (duration: 86.264236ms)","trace[1135194185] 'compare' (duration: 14.089405ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:52:26.049369Z","caller":"traceutil/trace.go:171","msg":"trace[1442780739] linearizableReadLoop","detail":"{readStateIndex:3904; appliedIndex:3903; }","duration":"144.255089ms","start":"2026-04-10T13:52:25.905084Z","end":"2026-04-10T13:52:26.049339Z","steps":["trace[1442780739] 'read index received' (duration: 143.94651ms)","trace[1442780739] 'applied index is now lower than readState.Index' (duration: 307.389µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:52:26.049562Z","caller":"traceutil/trace.go:171","msg":"trace[1504841732] transaction","detail":"{read_only:false; response_revision:3764; number_of_response:1; }","duration":"167.714979ms","start":"2026-04-10T13:52:25.881834Z","end":"2026-04-10T13:52:26.049549Z","steps":["trace[1504841732] 'process raft request' (duration: 167.330078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:52:26.049774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.68417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-client-secret\" ","response":"range_response_count:1 size:692"} {"level":"info","ts":"2026-04-10T13:52:26.049823Z","caller":"traceutil/trace.go:171","msg":"trace[339969475] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-client-secret; range_end:; response_count:1; response_revision:3764; }","duration":"144.790914ms","start":"2026-04-10T13:52:25.90502Z","end":"2026-04-10T13:52:26.049811Z","steps":["trace[339969475] 'agreement among raft nodes before linearized reading' (duration: 144.63907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:52:26.049933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.890858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:63176"} {"level":"info","ts":"2026-04-10T13:52:26.050028Z","caller":"traceutil/trace.go:171","msg":"trace[1229190655] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:3764; }","duration":"144.015392ms","start":"2026-04-10T13:52:25.90599Z","end":"2026-04-10T13:52:26.050005Z","steps":["trace[1229190655] 'agreement among raft nodes before linearized reading' (duration: 143.597661ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:53:51.026092Z","caller":"traceutil/trace.go:171","msg":"trace[1943566638] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"124.039988ms","start":"2026-04-10T13:53:50.902011Z","end":"2026-04-10T13:53:51.026051Z","steps":["trace[1943566638] 'process raft request' (duration: 123.847002ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:53:53.217039Z","caller":"traceutil/trace.go:171","msg":"trace[107163633] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5151; }","duration":"147.306048ms","start":"2026-04-10T13:53:53.069693Z","end":"2026-04-10T13:53:53.216999Z","steps":["trace[107163633] 'read index received' (duration: 84.121245ms)","trace[107163633] 'applied index is now lower than readState.Index' (duration: 63.183333ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:53:53.217103Z","caller":"traceutil/trace.go:171","msg":"trace[1035905097] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"184.883253ms","start":"2026-04-10T13:53:53.032188Z","end":"2026-04-10T13:53:53.217071Z","steps":["trace[1035905097] 'process raft request' (duration: 121.609387ms)","trace[1035905097] 'compare' (duration: 62.904776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:53:53.217306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.596766ms","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-10T13:53:53.217369Z","caller":"traceutil/trace.go:171","msg":"trace[879420564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4971; }","duration":"147.696679ms","start":"2026-04-10T13:53:53.069655Z","end":"2026-04-10T13:53:53.217352Z","steps":["trace[879420564] 'agreement among raft nodes before linearized reading' (duration: 147.482233ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:03.407799Z","caller":"traceutil/trace.go:171","msg":"trace[343516231] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"123.226291ms","start":"2026-04-10T13:54:03.284542Z","end":"2026-04-10T13:54:03.407769Z","steps":["trace[343516231] 'process raft request' (duration: 79.542199ms)","trace[343516231] 'compare' (duration: 43.362273ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:54:03.407833Z","caller":"traceutil/trace.go:171","msg":"trace[23686506] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"103.801751ms","start":"2026-04-10T13:54:03.30401Z","end":"2026-04-10T13:54:03.407812Z","steps":["trace[23686506] 'process raft request' (duration: 103.676208ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:11.255533Z","caller":"traceutil/trace.go:171","msg":"trace[731985483] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"131.339612ms","start":"2026-04-10T13:54:11.124145Z","end":"2026-04-10T13:54:11.255484Z","steps":["trace[731985483] 'process raft request' (duration: 131.035034ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:23.493916Z","caller":"traceutil/trace.go:171","msg":"trace[1835462010] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"162.650106ms","start":"2026-04-10T13:54:23.331241Z","end":"2026-04-10T13:54:23.493892Z","steps":["trace[1835462010] 'process raft request' (duration: 87.882477ms)","trace[1835462010] 'compare' (duration: 74.593964ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:54:23.494194Z","caller":"traceutil/trace.go:171","msg":"trace[1367592657] transaction","detail":"{read_only:false; response_revision:5490; number_of_response:1; }","duration":"162.285446ms","start":"2026-04-10T13:54:23.331884Z","end":"2026-04-10T13:54:23.494169Z","steps":["trace[1367592657] 'process raft request' (duration: 161.962677ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:23.49433Z","caller":"traceutil/trace.go:171","msg":"trace[1759210024] linearizableReadLoop","detail":"{readStateIndex:5687; appliedIndex:5683; }","duration":"144.008307ms","start":"2026-04-10T13:54:23.350312Z","end":"2026-04-10T13:54:23.494321Z","steps":["trace[1759210024] 'read index received' (duration: 11.714459ms)","trace[1759210024] 'applied index is now lower than readState.Index' (duration: 132.292458ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:54:23.494414Z","caller":"traceutil/trace.go:171","msg":"trace[2062203054] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"162.383058ms","start":"2026-04-10T13:54:23.332022Z","end":"2026-04-10T13:54:23.494405Z","steps":["trace[2062203054] 'process raft request' (duration: 162.034239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:54:23.497159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.838704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-10T13:54:23.497224Z","caller":"traceutil/trace.go:171","msg":"trace[350562219] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5492; }","duration":"146.911476ms","start":"2026-04-10T13:54:23.350292Z","end":"2026-04-10T13:54:23.497204Z","steps":["trace[350562219] 'agreement among raft nodes before linearized reading' (duration: 144.323986ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:33.932085Z","caller":"traceutil/trace.go:171","msg":"trace[1190405231] transaction","detail":"{read_only:false; response_revision:5573; number_of_response:1; }","duration":"101.704224ms","start":"2026-04-10T13:54:33.830357Z","end":"2026-04-10T13:54:33.932061Z","steps":["trace[1190405231] 'process raft request' (duration: 101.54701ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:33.93859Z","caller":"traceutil/trace.go:171","msg":"trace[861077946] transaction","detail":"{read_only:false; response_revision:5574; number_of_response:1; }","duration":"105.513568ms","start":"2026-04-10T13:54:33.833045Z","end":"2026-04-10T13:54:33.938559Z","steps":["trace[861077946] 'process raft request' (duration: 105.345003ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:55:35.774445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2986} {"level":"info","ts":"2026-04-10T13:55:35.848749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2986,"took":"72.115656ms","hash":208786087,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":33734656,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-10T13:55:35.848838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":208786087,"revision":2986,"compact-revision":-1} {"level":"info","ts":"2026-04-10T14:00:35.752398Z","caller":"traceutil/trace.go:171","msg":"trace[1087732752] linearizableReadLoop","detail":"{readStateIndex:8548; appliedIndex:8547; }","duration":"233.0173ms","start":"2026-04-10T14:00:35.519348Z","end":"2026-04-10T14:00:35.752365Z","steps":["trace[1087732752] 'read index received' (duration: 232.912437ms)","trace[1087732752] 'applied index is now lower than readState.Index' (duration: 103.483µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T14:00:35.752528Z","caller":"traceutil/trace.go:171","msg":"trace[98642731] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"247.274659ms","start":"2026-04-10T14:00:35.505215Z","end":"2026-04-10T14:00:35.752489Z","steps":["trace[98642731] 'process raft request' (duration: 246.93414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:00:35.752578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.207915ms","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-10T14:00:35.752651Z","caller":"traceutil/trace.go:171","msg":"trace[981672270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8263; }","duration":"233.359359ms","start":"2026-04-10T14:00:35.519276Z","end":"2026-04-10T14:00:35.752635Z","steps":["trace[981672270] 'agreement among raft nodes before linearized reading' (duration: 233.223825ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:00:35.883507Z","caller":"traceutil/trace.go:171","msg":"trace[964728366] transaction","detail":"{read_only:false; response_revision:8265; number_of_response:1; }","duration":"178.676374ms","start":"2026-04-10T14:00:35.704802Z","end":"2026-04-10T14:00:35.883479Z","steps":["trace[964728366] 'process raft request' (duration: 178.574841ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:00:35.883566Z","caller":"traceutil/trace.go:171","msg":"trace[601049821] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"360.971367ms","start":"2026-04-10T14:00:35.522532Z","end":"2026-04-10T14:00:35.883503Z","steps":["trace[601049821] 'process raft request' (duration: 308.675018ms)","trace[601049821] 'compare' (duration: 52.010242ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T14:00:35.883785Z","caller":"traceutil/trace.go:171","msg":"trace[24646706] transaction","detail":"{read_only:false; response_revision:8267; number_of_response:1; }","duration":"107.825308ms","start":"2026-04-10T14:00:35.775946Z","end":"2026-04-10T14:00:35.883772Z","steps":["trace[24646706] 'process raft request' (duration: 107.731985ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:00:35.883822Z","caller":"traceutil/trace.go:171","msg":"trace[517819947] linearizableReadLoop","detail":"{readStateIndex:8551; appliedIndex:8548; }","duration":"110.725237ms","start":"2026-04-10T14:00:35.773042Z","end":"2026-04-10T14:00:35.883767Z","steps":["trace[517819947] 'read index received' (duration: 58.219741ms)","trace[517819947] 'applied index is now lower than readState.Index' (duration: 52.503886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T14:00:35.883837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:00:35.522517Z","time spent":"361.160332ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T14:00:35.883871Z","caller":"traceutil/trace.go:171","msg":"trace[134426199] transaction","detail":"{read_only:false; response_revision:8266; number_of_response:1; }","duration":"126.372764ms","start":"2026-04-10T14:00:35.757423Z","end":"2026-04-10T14:00:35.883796Z","steps":["trace[134426199] 'process raft request' (duration: 126.015805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:00:35.88409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.015334ms","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-10T14:00:35.884188Z","caller":"traceutil/trace.go:171","msg":"trace[276244884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8267; }","duration":"111.19416ms","start":"2026-04-10T14:00:35.772975Z","end":"2026-04-10T14:00:35.884169Z","steps":["trace[276244884] 'agreement among raft nodes before linearized reading' (duration: 110.930382ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:00:35.887888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6067} {"level":"info","ts":"2026-04-10T14:00:35.99251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6067,"took":"100.322773ms","hash":198115691,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":23699456,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-10T14:00:35.992575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":198115691,"revision":6067,"compact-revision":2986} {"level":"info","ts":"2026-04-10T14:00:46.96694Z","caller":"traceutil/trace.go:171","msg":"trace[1016128243] transaction","detail":"{read_only:false; response_revision:8322; number_of_response:1; }","duration":"134.63935ms","start":"2026-04-10T14:00:46.832268Z","end":"2026-04-10T14:00:46.966907Z","steps":["trace[1016128243] 'process raft request' (duration: 134.426864ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:00:47.940625Z","caller":"traceutil/trace.go:171","msg":"trace[262138198] linearizableReadLoop","detail":"{readStateIndex:8613; appliedIndex:8612; }","duration":"100.345053ms","start":"2026-04-10T14:00:47.840255Z","end":"2026-04-10T14:00:47.9406Z","steps":["trace[262138198] 'read index received' (duration: 100.176399ms)","trace[262138198] 'applied index is now lower than readState.Index' (duration: 167.324µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T14:00:47.940807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.525888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:00:47.941195Z","caller":"traceutil/trace.go:171","msg":"trace[1726129177] transaction","detail":"{read_only:false; response_revision:8325; number_of_response:1; }","duration":"121.482071ms","start":"2026-04-10T14:00:47.819681Z","end":"2026-04-10T14:00:47.941163Z","steps":["trace[1726129177] 'process raft request' (duration: 120.771351ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:00:47.941505Z","caller":"traceutil/trace.go:171","msg":"trace[724139074] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8325; }","duration":"100.639021ms","start":"2026-04-10T14:00:47.840203Z","end":"2026-04-10T14:00:47.940842Z","steps":["trace[724139074] 'agreement among raft nodes before linearized reading' (duration: 100.520568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:00:47.971277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.434837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:00:47.971371Z","caller":"traceutil/trace.go:171","msg":"trace[1242100417] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8326; }","duration":"103.555701ms","start":"2026-04-10T14:00:47.8678Z","end":"2026-04-10T14:00:47.971356Z","steps":["trace[1242100417] 'agreement among raft nodes before linearized reading' (duration: 103.412927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:00:47.971489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.147045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:00:47.971544Z","caller":"traceutil/trace.go:171","msg":"trace[1306238610] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8326; }","duration":"131.233247ms","start":"2026-04-10T14:00:47.840299Z","end":"2026-04-10T14:00:47.971532Z","steps":["trace[1306238610] 'agreement among raft nodes before linearized reading' (duration: 131.155975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:00:47.97152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.712705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:00:47.97161Z","caller":"traceutil/trace.go:171","msg":"trace[1983297632] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8326; }","duration":"103.856009ms","start":"2026-04-10T14:00:47.867741Z","end":"2026-04-10T14:00:47.971597Z","steps":["trace[1983297632] 'agreement among raft nodes before linearized reading' (duration: 103.706394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:00:47.971691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.431634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:00:47.971749Z","caller":"traceutil/trace.go:171","msg":"trace[1684874274] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8326; }","duration":"108.524826ms","start":"2026-04-10T14:00:47.863209Z","end":"2026-04-10T14:00:47.971734Z","steps":["trace[1684874274] 'agreement among raft nodes before linearized reading' (duration: 108.389183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:00:48.030447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.510247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T14:00:48.030492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.132377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:00:48.030533Z","caller":"traceutil/trace.go:171","msg":"trace[671187002] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8326; }","duration":"115.636741ms","start":"2026-04-10T14:00:47.914879Z","end":"2026-04-10T14:00:48.030516Z","steps":["trace[671187002] 'agreement among raft nodes before linearized reading' (duration: 56.947907ms)","trace[671187002] 'range keys from in-memory index tree' (duration: 58.567911ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T14:00:48.030544Z","caller":"traceutil/trace.go:171","msg":"trace[306557200] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8326; }","duration":"190.20175ms","start":"2026-04-10T14:00:47.840329Z","end":"2026-04-10T14:00:48.030531Z","steps":["trace[306557200] 'agreement among raft nodes before linearized reading' (duration: 131.388432ms)","trace[306557200] 'range keys from in-memory index tree' (duration: 58.742226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T14:00:48.030569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.532734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:00:48.030634Z","caller":"traceutil/trace.go:171","msg":"trace[638674696] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8326; }","duration":"135.614157ms","start":"2026-04-10T14:00:47.895002Z","end":"2026-04-10T14:00:48.030616Z","steps":["trace[638674696] 'agreement among raft nodes before linearized reading' (duration: 76.8353ms)","trace[638674696] 'range keys from in-memory index tree' (duration: 58.693735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T14:00:48.030927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.971354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:00:48.030959Z","caller":"traceutil/trace.go:171","msg":"trace[1450183608] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8326; }","duration":"137.027835ms","start":"2026-04-10T14:00:47.893923Z","end":"2026-04-10T14:00:48.030951Z","steps":["trace[1450183608] 'agreement among raft nodes before linearized reading' (duration: 77.796426ms)","trace[1450183608] 'range keys from in-memory index tree' (duration: 59.187538ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T14:00:48.314203Z","caller":"traceutil/trace.go:171","msg":"trace[1965662290] linearizableReadLoop","detail":"{readStateIndex:8618; appliedIndex:8617; }","duration":"128.096991ms","start":"2026-04-10T14:00:48.186078Z","end":"2026-04-10T14:00:48.314175Z","steps":["trace[1965662290] 'read index received' (duration: 71.313839ms)","trace[1965662290] 'applied index is now lower than readState.Index' (duration: 56.782202ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T14:00:48.314316Z","caller":"traceutil/trace.go:171","msg":"trace[1653820432] transaction","detail":"{read_only:false; response_revision:8330; number_of_response:1; }","duration":"191.71845ms","start":"2026-04-10T14:00:48.122539Z","end":"2026-04-10T14:00:48.314257Z","steps":["trace[1653820432] 'process raft request' (duration: 134.877157ms)","trace[1653820432] 'compare' (duration: 56.620277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T14:00:48.31463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.525424ms","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-10T14:00:48.314717Z","caller":"traceutil/trace.go:171","msg":"trace[616217745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8330; }","duration":"128.653456ms","start":"2026-04-10T14:00:48.186045Z","end":"2026-04-10T14:00:48.314698Z","steps":["trace[616217745] 'agreement among raft nodes before linearized reading' (duration: 128.233025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:00:49.104937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.763692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T14:00:49.105272Z","caller":"traceutil/trace.go:171","msg":"trace[811098049] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"190.745874ms","start":"2026-04-10T14:00:48.914501Z","end":"2026-04-10T14:00:49.105247Z","steps":["trace[811098049] 'process raft request' (duration: 47.606652ms)","trace[811098049] 'compare' (duration: 142.389531ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T14:00:49.105691Z","caller":"traceutil/trace.go:171","msg":"trace[426598055] transaction","detail":"{read_only:false; response_revision:8335; number_of_response:1; }","duration":"133.075606ms","start":"2026-04-10T14:00:48.972605Z","end":"2026-04-10T14:00:49.10568Z","steps":["trace[426598055] 'process raft request' (duration: 132.590813ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:00:49.468731Z","caller":"traceutil/trace.go:171","msg":"trace[505050641] transaction","detail":"{read_only:false; response_revision:8337; number_of_response:1; }","duration":"153.588399ms","start":"2026-04-10T14:00:49.315118Z","end":"2026-04-10T14:00:49.468706Z","steps":["trace[505050641] 'process raft request' (duration: 153.427034ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:13.578736Z","caller":"traceutil/trace.go:171","msg":"trace[1380143618] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"102.06808ms","start":"2026-04-10T14:01:13.476643Z","end":"2026-04-10T14:01:13.578711Z","steps":["trace[1380143618] 'process raft request' (duration: 101.935097ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:17.115088Z","caller":"traceutil/trace.go:171","msg":"trace[736510839] linearizableReadLoop","detail":"{readStateIndex:8835; appliedIndex:8834; }","duration":"281.790064ms","start":"2026-04-10T14:01:16.83328Z","end":"2026-04-10T14:01:17.11507Z","steps":["trace[736510839] 'read index received' (duration: 281.621239ms)","trace[736510839] 'applied index is now lower than readState.Index' (duration: 168.175µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T14:01:17.115387Z","caller":"traceutil/trace.go:171","msg":"trace[358842047] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"291.527299ms","start":"2026-04-10T14:01:16.823853Z","end":"2026-04-10T14:01:17.115381Z","steps":["trace[358842047] 'process raft request' (duration: 291.099497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:01:17.115532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.246546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:01:17.11555Z","caller":"traceutil/trace.go:171","msg":"trace[102639543] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8538; }","duration":"282.302158ms","start":"2026-04-10T14:01:16.833243Z","end":"2026-04-10T14:01:17.115545Z","steps":["trace[102639543] 'agreement among raft nodes before linearized reading' (duration: 282.255136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:01:17.115679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.235913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-slhml\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-10T14:01:17.115692Z","caller":"traceutil/trace.go:171","msg":"trace[553278453] range","detail":"{range_begin:/registry/pods/openstack/keepalived-slhml; range_end:; response_count:1; response_revision:8538; }","duration":"174.249853ms","start":"2026-04-10T14:01:16.941439Z","end":"2026-04-10T14:01:17.115689Z","steps":["trace[553278453] 'agreement among raft nodes before linearized reading' (duration: 174.185322ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:57.012473Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242c.wal"} {"level":"info","ts":"2026-04-10T14:02:46.782004Z","caller":"traceutil/trace.go:171","msg":"trace[1337881537] linearizableReadLoop","detail":"{readStateIndex:9721; appliedIndex:9720; }","duration":"170.401626ms","start":"2026-04-10T14:02:46.611575Z","end":"2026-04-10T14:02:46.781977Z","steps":["trace[1337881537] 'read index received' (duration: 170.079707ms)","trace[1337881537] 'applied index is now lower than readState.Index' (duration: 320.439µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T14:02:46.782063Z","caller":"traceutil/trace.go:171","msg":"trace[301354441] transaction","detail":"{read_only:false; response_revision:9399; number_of_response:1; }","duration":"294.006242ms","start":"2026-04-10T14:02:46.488026Z","end":"2026-04-10T14:02:46.782032Z","steps":["trace[301354441] 'process raft request' (duration: 293.782246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:02:46.782322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.735064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-10T14:02:46.782387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.067288ms","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-10T14:02:46.782449Z","caller":"traceutil/trace.go:171","msg":"trace[1655691870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9399; }","duration":"117.201402ms","start":"2026-04-10T14:02:46.665235Z","end":"2026-04-10T14:02:46.782436Z","steps":["trace[1655691870] 'agreement among raft nodes before linearized reading' (duration: 117.005627ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:02:46.782371Z","caller":"traceutil/trace.go:171","msg":"trace[1582575290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9399; }","duration":"170.845367ms","start":"2026-04-10T14:02:46.611513Z","end":"2026-04-10T14:02:46.782359Z","steps":["trace[1582575290] 'agreement among raft nodes before linearized reading' (duration: 170.620261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:02:46.782574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.128834ms","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-10T14:02:46.782726Z","caller":"traceutil/trace.go:171","msg":"trace[744362573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9399; }","duration":"134.344911ms","start":"2026-04-10T14:02:46.648359Z","end":"2026-04-10T14:02:46.782704Z","steps":["trace[744362573] 'agreement among raft nodes before linearized reading' (duration: 133.864667ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:02:46.887811Z","caller":"traceutil/trace.go:171","msg":"trace[862936813] transaction","detail":"{read_only:false; response_revision:9402; number_of_response:1; }","duration":"100.328981ms","start":"2026-04-10T14:02:46.787454Z","end":"2026-04-10T14:02:46.887783Z","steps":["trace[862936813] 'process raft request' (duration: 100.226339ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:02:46.887982Z","caller":"traceutil/trace.go:171","msg":"trace[167874773] transaction","detail":"{read_only:false; response_revision:9401; number_of_response:1; }","duration":"100.531157ms","start":"2026-04-10T14:02:46.787416Z","end":"2026-04-10T14:02:46.887947Z","steps":["trace[167874773] 'process raft request' (duration: 89.863606ms)","trace[167874773] 'compare' (duration: 10.25194ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T14:02:46.888528Z","caller":"traceutil/trace.go:171","msg":"trace[150353146] transaction","detail":"{read_only:false; response_revision:9403; number_of_response:1; }","duration":"100.29194ms","start":"2026-04-10T14:02:46.788195Z","end":"2026-04-10T14:02:46.888487Z","steps":["trace[150353146] 'process raft request' (duration: 99.545079ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:03:23.093263Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db79120667ce701f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-10T14:03:23.10023Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-10T14:03:23.100334Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-04-10T14:04:54.930282Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079349393705368647,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T14:04:56.710492Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.697251473s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-10T14:04:56.717571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.704347397s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-10T14:04:56.718264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.615816431s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:04:56.718363Z","caller":"traceutil/trace.go:171","msg":"trace[771484188] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:10306; }","duration":"1.615925265s","start":"2026-04-10T14:04:55.102423Z","end":"2026-04-10T14:04:56.718348Z","steps":["trace[771484188] 'range keys from in-memory index tree' (duration: 1.615802091s)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:04:56.71847Z","caller":"traceutil/trace.go:171","msg":"trace[1594206186] transaction","detail":"{read_only:false; response_revision:10307; number_of_response:1; }","duration":"2.485923382s","start":"2026-04-10T14:04:54.232527Z","end":"2026-04-10T14:04:56.71845Z","steps":["trace[1594206186] 'process raft request' (duration: 2.485162811s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.718582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:54.232488Z","time spent":"2.486031785s","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T14:04:56.718721Z","caller":"traceutil/trace.go:171","msg":"trace[1084033351] linearizableReadLoop","detail":"{readStateIndex:10661; appliedIndex:10657; }","duration":"2.288700198s","start":"2026-04-10T14:04:54.430011Z","end":"2026-04-10T14:04:56.718712Z","steps":["trace[1084033351] 'read index received' (duration: 583.192399ms)","trace[1084033351] 'applied index is now lower than readState.Index' (duration: 1.705507119s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T14:04:56.718815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.28880333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:04:56.718797Z","caller":"traceutil/trace.go:171","msg":"trace[571395578] transaction","detail":"{read_only:false; response_revision:10309; number_of_response:1; }","duration":"2.37051486s","start":"2026-04-10T14:04:54.348254Z","end":"2026-04-10T14:04:56.718768Z","steps":["trace[571395578] 'process raft request' (duration: 2.370377766s)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:04:56.718847Z","caller":"traceutil/trace.go:171","msg":"trace[2070739953] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:10309; }","duration":"2.288883472s","start":"2026-04-10T14:04:54.429954Z","end":"2026-04-10T14:04:56.718838Z","steps":["trace[2070739953] 'agreement among raft nodes before linearized reading' (duration: 2.288815271s)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:04:56.718787Z","caller":"traceutil/trace.go:171","msg":"trace[856702688] transaction","detail":"{read_only:false; response_revision:10308; number_of_response:1; }","duration":"2.478139658s","start":"2026-04-10T14:04:54.24061Z","end":"2026-04-10T14:04:56.71875Z","steps":["trace[856702688] 'process raft request' (duration: 2.477795879s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.718874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:54.429931Z","time spent":"2.288933264s","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-10T14:04:56.718919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:54.348217Z","time spent":"2.370634143s","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T14:04:56.718976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:54.240582Z","time spent":"2.478302243s","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T14:04:56.720954Z","caller":"traceutil/trace.go:171","msg":"trace[1026933914] transaction","detail":"{read_only:false; response_revision:10310; number_of_response:1; }","duration":"1.462910692s","start":"2026-04-10T14:04:55.258006Z","end":"2026-04-10T14:04:56.720916Z","steps":["trace[1026933914] 'process raft request' (duration: 1.462647635s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.721114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:55.257978Z","time spent":"1.463049475s","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T14:04:56.721197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187955788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-10T14:04:56.721202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033899997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-10T14:04:56.721269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.818605ms","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-10T14:04:56.721302Z","caller":"traceutil/trace.go:171","msg":"trace[246908649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10311; }","duration":"1.034068112s","start":"2026-04-10T14:04:55.687211Z","end":"2026-04-10T14:04:56.721279Z","steps":["trace[246908649] 'agreement among raft nodes before linearized reading' (duration: 1.033748124s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.721361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075804545s","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-10T14:04:56.721361Z","caller":"traceutil/trace.go:171","msg":"trace[1764381072] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:10311; }","duration":"399.947079ms","start":"2026-04-10T14:04:56.321389Z","end":"2026-04-10T14:04:56.721336Z","steps":["trace[1764381072] 'agreement among raft nodes before linearized reading' (duration: 399.783214ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:04:56.721396Z","caller":"traceutil/trace.go:171","msg":"trace[447706451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10311; }","duration":"1.075865587s","start":"2026-04-10T14:04:55.645521Z","end":"2026-04-10T14:04:56.721387Z","steps":["trace[447706451] 'agreement among raft nodes before linearized reading' (duration: 1.075796725s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.721422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:55.645506Z","time spent":"1.075910508s","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T14:04:56.721418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.703395551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T14:04:56.721435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.567616111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T14:04:56.721473Z","caller":"traceutil/trace.go:171","msg":"trace[1603848947] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:10311; }","duration":"1.567681062s","start":"2026-04-10T14:04:55.153782Z","end":"2026-04-10T14:04:56.721464Z","steps":["trace[1603848947] 'agreement among raft nodes before linearized reading' (duration: 1.56759829s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.72148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.388414ms","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-10T14:04:56.721461Z","caller":"traceutil/trace.go:171","msg":"trace[1926675736] transaction","detail":"{read_only:false; response_revision:10311; number_of_response:1; }","duration":"590.777387ms","start":"2026-04-10T14:04:56.130621Z","end":"2026-04-10T14:04:56.721399Z","steps":["trace[1926675736] 'process raft request' (duration: 590.195441ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:04:56.721483Z","caller":"traceutil/trace.go:171","msg":"trace[1338269259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10311; }","duration":"1.703485703s","start":"2026-04-10T14:04:55.017975Z","end":"2026-04-10T14:04:56.721461Z","steps":["trace[1338269259] 'agreement among raft nodes before linearized reading' (duration: 1.703415481s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.721509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:55.153764Z","time spent":"1.567739514s","remote":"127.0.0.1:51660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":21,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-04-10T14:04:56.721514Z","caller":"traceutil/trace.go:171","msg":"trace[311626755] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10311; }","duration":"495.447055ms","start":"2026-04-10T14:04:56.226059Z","end":"2026-04-10T14:04:56.721506Z","steps":["trace[311626755] 'agreement among raft nodes before linearized reading' (duration: 495.326061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.721535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:56.226043Z","time spent":"495.486876ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-10T14:04:56.721538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:55.017955Z","time spent":"1.703571866s","remote":"127.0.0.1:51140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T14:04:56.721363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:55.68718Z","time spent":"1.034172325s","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-10T14:04:56.721584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:56.130602Z","time spent":"590.922311ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T14:04:56.721302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075131377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-10T14:04:56.721672Z","caller":"traceutil/trace.go:171","msg":"trace[969009026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10311; }","duration":"1.075515448s","start":"2026-04-10T14:04:55.646147Z","end":"2026-04-10T14:04:56.721663Z","steps":["trace[969009026] 'agreement among raft nodes before linearized reading' (duration: 1.075111717s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.721702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:55.64614Z","time spent":"1.075553959s","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-10T14:04:56.721352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.630428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414770"} {"level":"info","ts":"2026-04-10T14:04:56.721763Z","caller":"traceutil/trace.go:171","msg":"trace[1583000406] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:10311; }","duration":"185.105071ms","start":"2026-04-10T14:04:56.536651Z","end":"2026-04-10T14:04:56.721756Z","steps":["trace[1583000406] 'agreement among raft nodes before linearized reading' (duration: 184.476784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.721418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:56.321375Z","time spent":"400.028651ms","remote":"127.0.0.1:51246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-10T14:04:56.721226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.240197799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-slhml\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-10T14:04:56.722002Z","caller":"traceutil/trace.go:171","msg":"trace[1978945183] range","detail":"{range_begin:/registry/pods/openstack/keepalived-slhml; range_end:; response_count:1; response_revision:10311; }","duration":"2.241004121s","start":"2026-04-10T14:04:54.480976Z","end":"2026-04-10T14:04:56.72198Z","steps":["trace[1978945183] 'agreement among raft nodes before linearized reading' (duration: 2.240081386s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.722046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:54.48096Z","time spent":"2.241075023s","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-slhml\" "} {"level":"info","ts":"2026-04-10T14:04:56.72124Z","caller":"traceutil/trace.go:171","msg":"trace[52806039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10311; }","duration":"1.188047411s","start":"2026-04-10T14:04:55.533181Z","end":"2026-04-10T14:04:56.721229Z","steps":["trace[52806039] 'agreement among raft nodes before linearized reading' (duration: 1.187928108s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.722105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:55.533168Z","time spent":"1.188918494s","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T14:04:56.721358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298107756s","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-10T14:04:56.722237Z","caller":"traceutil/trace.go:171","msg":"trace[1824216000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10311; }","duration":"1.299016441s","start":"2026-04-10T14:04:55.42321Z","end":"2026-04-10T14:04:56.722226Z","steps":["trace[1824216000] 'agreement among raft nodes before linearized reading' (duration: 1.298062325s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:04:56.722272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:04:55.423193Z","time spent":"1.299070133s","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-10T14:05:35.897575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8267} {"level":"info","ts":"2026-04-10T14:05:35.984717Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8267,"took":"84.888956ms","hash":2127810023,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":25776128,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-10T14:05:35.984785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2127810023,"revision":8267,"compact-revision":6067} {"level":"info","ts":"2026-04-10T14:10:35.906322Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10654} {"level":"info","ts":"2026-04-10T14:10:35.980882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10654,"took":"72.328761ms","hash":1666059150,"current-db-size-bytes":57921536,"current-db-size":"58 MB","current-db-size-in-use-bytes":20975616,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-10T14:10:35.98096Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1666059150,"revision":10654,"compact-revision":8267}