{"level":"warn","ts":"2026-08-28T13:10:13.997521Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-28T13:10:13.997651Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.14: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://162.253.55.14:2380","--initial-cluster=instance=https://162.253.55.14:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.14:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.14: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-08-28T13:10:13.997753Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-28T13:10:13.997781Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-08-28T13:10:13.997822Z","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-08-28T13:10:13.998952Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"]} {"level":"info","ts":"2026-08-28T13:10:13.999081Z","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://162.253.55.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.14: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-08-28T13:10:14.003899Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.349012ms"} {"level":"info","ts":"2026-08-28T13:10:14.011558Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5db0a1b394e67b72","cluster-id":"cfda6ccfe21bda40"} {"level":"info","ts":"2026-08-28T13:10:14.011655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=()"} {"level":"info","ts":"2026-08-28T13:10:14.011685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 0"} {"level":"info","ts":"2026-08-28T13:10:14.011703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5db0a1b394e67b72 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-28T13:10:14.011718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 1"} {"level":"info","ts":"2026-08-28T13:10:14.011768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"warn","ts":"2026-08-28T13:10:14.024837Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-28T13:10:14.026654Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-28T13:10:14.028165Z","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-08-28T13:10:14.030911Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5db0a1b394e67b72","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-28T13:10:14.03154Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5db0a1b394e67b72","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-28T13:10:14.031638Z","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-08-28T13:10:14.031821Z","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-08-28T13:10:14.031837Z","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-08-28T13:10:14.032331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"info","ts":"2026-08-28T13:10:14.035338Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","added-peer-id":"5db0a1b394e67b72","added-peer-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-08-28T13:10:14.039976Z","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-08-28T13:10:14.040132Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-08-28T13:10:14.04018Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-08-28T13:10:14.040327Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5db0a1b394e67b72","initial-advertise-peer-urls":["https://162.253.55.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-28T13:10:14.040368Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-28T13:10:14.412671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 is starting a new election at term 1"} {"level":"info","ts":"2026-08-28T13:10:14.412754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-28T13:10:14.412778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgPreVoteResp from 5db0a1b394e67b72 at term 1"} {"level":"info","ts":"2026-08-28T13:10:14.412801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became candidate at term 2"} {"level":"info","ts":"2026-08-28T13:10:14.412813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgVoteResp from 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-08-28T13:10:14.412828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became leader at term 2"} {"level":"info","ts":"2026-08-28T13:10:14.412842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5db0a1b394e67b72 elected leader 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-08-28T13:10:14.414325Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5db0a1b394e67b72","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.14:2379]}","request-path":"/0/members/5db0a1b394e67b72/attributes","cluster-id":"cfda6ccfe21bda40","publish-timeout":"7s"} {"level":"info","ts":"2026-08-28T13:10:14.41434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T13:10:14.414534Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T13:10:14.414391Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T13:10:14.414739Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-28T13:10:14.414814Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-28T13:10:14.416914Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T13:10:14.41714Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T13:10:14.41723Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T13:10:14.418391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.14:2379"} {"level":"info","ts":"2026-08-28T13:10:14.419102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-28T13:10:59.511583Z","caller":"traceutil/trace.go:171","msg":"trace[221989509] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"140.320469ms","start":"2026-08-28T13:10:59.371215Z","end":"2026-08-28T13:10:59.511536Z","steps":["trace[221989509] 'process raft request' (duration: 139.9704ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:10:59.512127Z","caller":"traceutil/trace.go:171","msg":"trace[1682833106] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"127.997207ms","start":"2026-08-28T13:10:59.384097Z","end":"2026-08-28T13:10:59.512094Z","steps":["trace[1682833106] 'process raft request' (duration: 127.828323ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:10:59.512162Z","caller":"traceutil/trace.go:171","msg":"trace[945178173] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"121.79274ms","start":"2026-08-28T13:10:59.390336Z","end":"2026-08-28T13:10:59.512129Z","steps":["trace[945178173] 'process raft request' (duration: 121.634306ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:10:59.512251Z","caller":"traceutil/trace.go:171","msg":"trace[590707654] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:573; }","duration":"116.187178ms","start":"2026-08-28T13:10:59.395821Z","end":"2026-08-28T13:10:59.512008Z","steps":["trace[590707654] 'read index received' (duration: 115.428758ms)","trace[590707654] 'applied index is now lower than readState.Index' (duration: 757.11µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:10:59.512419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.556006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-08-28T13:10:59.51214Z","caller":"traceutil/trace.go:171","msg":"trace[270856187] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"132.412769ms","start":"2026-08-28T13:10:59.379643Z","end":"2026-08-28T13:10:59.512056Z","steps":["trace[270856187] 'process raft request' (duration: 131.817973ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:10:59.512539Z","caller":"traceutil/trace.go:171","msg":"trace[1568069669] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:554; }","duration":"116.757532ms","start":"2026-08-28T13:10:59.395767Z","end":"2026-08-28T13:10:59.512524Z","steps":["trace[1568069669] 'agreement among raft nodes before linearized reading' (duration: 116.541296ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:10:59.516688Z","caller":"traceutil/trace.go:171","msg":"trace[942908399] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"111.696025ms","start":"2026-08-28T13:10:59.404974Z","end":"2026-08-28T13:10:59.51667Z","steps":["trace[942908399] 'process raft request' (duration: 111.208832ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:10:59.516959Z","caller":"traceutil/trace.go:171","msg":"trace[827432027] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"110.938725ms","start":"2026-08-28T13:10:59.406004Z","end":"2026-08-28T13:10:59.516942Z","steps":["trace[827432027] 'process raft request' (duration: 110.387132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:11:07.24901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.941664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T13:11:07.249142Z","caller":"traceutil/trace.go:171","msg":"trace[1775510161] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"140.77532ms","start":"2026-08-28T13:11:07.108348Z","end":"2026-08-28T13:11:07.249123Z","steps":["trace[1775510161] 'read index received' (duration: 80.712µs)","trace[1775510161] 'applied index is now lower than readState.Index' (duration: 140.692448ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:11:07.249293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.947074ms","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-08-28T13:11:07.249341Z","caller":"traceutil/trace.go:171","msg":"trace[20813921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"141.043037ms","start":"2026-08-28T13:11:07.108285Z","end":"2026-08-28T13:11:07.249328Z","steps":["trace[20813921] 'agreement among raft nodes before linearized reading' (duration: 140.947595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:11:07.249525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.64961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-28T13:11:07.249598Z","caller":"traceutil/trace.go:171","msg":"trace[717776914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"135.785293ms","start":"2026-08-28T13:11:07.113796Z","end":"2026-08-28T13:11:07.249581Z","steps":["trace[717776914] 'agreement among raft nodes before linearized reading' (duration: 135.516687ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:11:07.249793Z","caller":"traceutil/trace.go:171","msg":"trace[687288834] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"283.230382ms","start":"2026-08-28T13:11:06.966532Z","end":"2026-08-28T13:11:07.249762Z","steps":["trace[687288834] 'process raft request' (duration: 91.916254ms)","trace[687288834] 'compare' (duration: 189.556294ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:11:07.890611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.710559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T13:11:07.890759Z","caller":"traceutil/trace.go:171","msg":"trace[599311563] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"182.349432ms","start":"2026-08-28T13:11:07.708388Z","end":"2026-08-28T13:11:07.890737Z","steps":["trace[599311563] 'read index received' (duration: 64.953µs)","trace[599311563] 'applied index is now lower than readState.Index' (duration: 182.282569ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:11:07.890811Z","caller":"traceutil/trace.go:171","msg":"trace[1677100237] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"285.241844ms","start":"2026-08-28T13:11:07.605528Z","end":"2026-08-28T13:11:07.89077Z","steps":["trace[1677100237] 'process raft request' (duration: 89.194376ms)","trace[1677100237] 'compare' (duration: 195.468863ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:11:07.890969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.583367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b7b6g\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-28T13:11:07.891023Z","caller":"traceutil/trace.go:171","msg":"trace[1683440063] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b7b6g; range_end:; response_count:1; response_revision:653; }","duration":"182.69138ms","start":"2026-08-28T13:11:07.708319Z","end":"2026-08-28T13:11:07.89101Z","steps":["trace[1683440063] 'agreement among raft nodes before linearized reading' (duration: 182.477405ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:11:07.919813Z","caller":"traceutil/trace.go:171","msg":"trace[497633653] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"170.749178ms","start":"2026-08-28T13:11:07.749024Z","end":"2026-08-28T13:11:07.919773Z","steps":["trace[497633653] 'process raft request' (duration: 165.582837ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:11:13.17412Z","caller":"traceutil/trace.go:171","msg":"trace[2080112878] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"136.268996ms","start":"2026-08-28T13:11:13.037817Z","end":"2026-08-28T13:11:13.174086Z","steps":["trace[2080112878] 'process raft request' (duration: 136.02414ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:11:26.056454Z","caller":"traceutil/trace.go:171","msg":"trace[1077064763] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"214.220808ms","start":"2026-08-28T13:11:25.842197Z","end":"2026-08-28T13:11:26.056418Z","steps":["trace[1077064763] 'read index received' (duration: 213.853448ms)","trace[1077064763] 'applied index is now lower than readState.Index' (duration: 365.47µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:11:26.056511Z","caller":"traceutil/trace.go:171","msg":"trace[134301181] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"220.875866ms","start":"2026-08-28T13:11:25.835598Z","end":"2026-08-28T13:11:26.056474Z","steps":["trace[134301181] 'process raft request' (duration: 220.595958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:11:26.05667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.442733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:11:26.056761Z","caller":"traceutil/trace.go:171","msg":"trace[287823908] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:826; }","duration":"214.575256ms","start":"2026-08-28T13:11:25.842162Z","end":"2026-08-28T13:11:26.056737Z","steps":["trace[287823908] 'agreement among raft nodes before linearized reading' (duration: 214.417383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:11:26.056886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.497054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T13:11:26.057015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.56457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:11:26.057Z","caller":"traceutil/trace.go:171","msg":"trace[133191056] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:826; }","duration":"214.763271ms","start":"2026-08-28T13:11:25.842216Z","end":"2026-08-28T13:11:26.05698Z","steps":["trace[133191056] 'agreement among raft nodes before linearized reading' (duration: 214.506995ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:11:26.057072Z","caller":"traceutil/trace.go:171","msg":"trace[1460464759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:826; }","duration":"107.625731ms","start":"2026-08-28T13:11:25.949432Z","end":"2026-08-28T13:11:26.057057Z","steps":["trace[1460464759] 'agreement among raft nodes before linearized reading' (duration: 107.548039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:11:26.057098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.863298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T13:11:26.057123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.792035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:11:26.057183Z","caller":"traceutil/trace.go:171","msg":"trace[697443282] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:826; }","duration":"213.94761ms","start":"2026-08-28T13:11:25.843206Z","end":"2026-08-28T13:11:26.057153Z","steps":["trace[697443282] 'agreement among raft nodes before linearized reading' (duration: 213.833538ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:11:26.057194Z","caller":"traceutil/trace.go:171","msg":"trace[1874009363] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:826; }","duration":"107.904978ms","start":"2026-08-28T13:11:25.949266Z","end":"2026-08-28T13:11:26.057171Z","steps":["trace[1874009363] 'agreement among raft nodes before linearized reading' (duration: 107.739404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:11:26.057219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.027306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:11:26.057325Z","caller":"traceutil/trace.go:171","msg":"trace[70804319] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:826; }","duration":"107.208681ms","start":"2026-08-28T13:11:25.950092Z","end":"2026-08-28T13:11:26.057301Z","steps":["trace[70804319] 'agreement among raft nodes before linearized reading' (duration: 106.945724ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:12:50.980022Z","caller":"traceutil/trace.go:171","msg":"trace[1173850926] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"134.374576ms","start":"2026-08-28T13:12:50.845603Z","end":"2026-08-28T13:12:50.979977Z","steps":["trace[1173850926] 'process raft request' (duration: 134.087119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:12:51.529676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.648555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-76593947-88bd-4d84-9a6b-8ebce249f9ab\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-28T13:12:51.529802Z","caller":"traceutil/trace.go:171","msg":"trace[329200554] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-76593947-88bd-4d84-9a6b-8ebce249f9ab; range_end:; response_count:1; response_revision:1932; }","duration":"226.81659ms","start":"2026-08-28T13:12:51.302945Z","end":"2026-08-28T13:12:51.529762Z","steps":["trace[329200554] 'range keys from in-memory index tree' (duration: 226.381799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:12:51.529809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.0271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-76593947-88bd-4d84-9a6b-8ebce249f9ab\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-28T13:12:51.529952Z","caller":"traceutil/trace.go:171","msg":"trace[574222118] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-76593947-88bd-4d84-9a6b-8ebce249f9ab; range_end:; response_count:1; response_revision:1932; }","duration":"386.204215ms","start":"2026-08-28T13:12:51.143693Z","end":"2026-08-28T13:12:51.529897Z","steps":["trace[574222118] 'range keys from in-memory index tree' (duration: 385.829625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:12:51.529947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.882898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T13:12:51.530045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:12:51.143669Z","time spent":"386.355629ms","remote":"127.0.0.1:59648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-76593947-88bd-4d84-9a6b-8ebce249f9ab\" "} {"level":"info","ts":"2026-08-28T13:12:51.530122Z","caller":"traceutil/trace.go:171","msg":"trace[362745666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1932; }","duration":"256.075273ms","start":"2026-08-28T13:12:51.273992Z","end":"2026-08-28T13:12:51.530067Z","steps":["trace[362745666] 'range keys from in-memory index tree' (duration: 255.741054ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:12:51.533873Z","caller":"traceutil/trace.go:171","msg":"trace[1922430477] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"217.784349ms","start":"2026-08-28T13:12:51.316062Z","end":"2026-08-28T13:12:51.533847Z","steps":["trace[1922430477] 'process raft request' (duration: 217.609975ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:12:51.574387Z","caller":"traceutil/trace.go:171","msg":"trace[1553744819] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"211.308072ms","start":"2026-08-28T13:12:51.363012Z","end":"2026-08-28T13:12:51.57432Z","steps":["trace[1553744819] 'process raft request' (duration: 210.977774ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:13:47.523349Z","caller":"traceutil/trace.go:171","msg":"trace[1388421209] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"159.531891ms","start":"2026-08-28T13:13:47.363765Z","end":"2026-08-28T13:13:47.523297Z","steps":["trace[1388421209] 'process raft request' (duration: 159.14106ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:13:47.960139Z","caller":"traceutil/trace.go:171","msg":"trace[1492260202] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"152.550712ms","start":"2026-08-28T13:13:47.807562Z","end":"2026-08-28T13:13:47.960113Z","steps":["trace[1492260202] 'process raft request' (duration: 152.394798ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:13:47.9643Z","caller":"traceutil/trace.go:171","msg":"trace[1766012159] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"107.469918ms","start":"2026-08-28T13:13:47.856802Z","end":"2026-08-28T13:13:47.964272Z","steps":["trace[1766012159] 'process raft request' (duration: 107.251633ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:14:46.32442Z","caller":"traceutil/trace.go:171","msg":"trace[2067833506] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"126.061595ms","start":"2026-08-28T13:14:46.198324Z","end":"2026-08-28T13:14:46.324385Z","steps":["trace[2067833506] 'process raft request' (duration: 122.179042ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:14:46.324493Z","caller":"traceutil/trace.go:171","msg":"trace[794875316] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"124.813922ms","start":"2026-08-28T13:14:46.199631Z","end":"2026-08-28T13:14:46.324445Z","steps":["trace[794875316] 'process raft request' (duration: 124.676788ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:15:23.078879Z","caller":"traceutil/trace.go:171","msg":"trace[500918074] transaction","detail":"{read_only:false; response_revision:3005; number_of_response:1; }","duration":"126.133077ms","start":"2026-08-28T13:15:22.95271Z","end":"2026-08-28T13:15:23.078843Z","steps":["trace[500918074] 'process raft request' (duration: 125.921022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:15:23.367443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.434112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-28T13:15:23.367537Z","caller":"traceutil/trace.go:171","msg":"trace[1195624974] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3005; }","duration":"239.588805ms","start":"2026-08-28T13:15:23.127926Z","end":"2026-08-28T13:15:23.367515Z","steps":["trace[1195624974] 'range keys from in-memory index tree' (duration: 239.136144ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:15:23.367519Z","caller":"traceutil/trace.go:171","msg":"trace[925501073] transaction","detail":"{read_only:false; response_revision:3006; number_of_response:1; }","duration":"229.407793ms","start":"2026-08-28T13:15:23.138058Z","end":"2026-08-28T13:15:23.367466Z","steps":["trace[925501073] 'process raft request' (duration: 213.959261ms)","trace[925501073] 'compare' (duration: 14.97709ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:18:24.491573Z","caller":"traceutil/trace.go:171","msg":"trace[1436962801] transaction","detail":"{read_only:false; response_revision:4085; number_of_response:1; }","duration":"101.668311ms","start":"2026-08-28T13:18:24.389845Z","end":"2026-08-28T13:18:24.491514Z","steps":["trace[1436962801] 'process raft request' (duration: 101.356323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:18:52.250701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.6488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-08-28T13:18:52.250811Z","caller":"traceutil/trace.go:171","msg":"trace[2075731200] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4300; }","duration":"104.788513ms","start":"2026-08-28T13:18:52.146003Z","end":"2026-08-28T13:18:52.250791Z","steps":["trace[2075731200] 'range keys from in-memory index tree' (duration: 104.537817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:18:57.864854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.401336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-28T13:18:57.864936Z","caller":"traceutil/trace.go:171","msg":"trace[2010646999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4325; }","duration":"103.56985ms","start":"2026-08-28T13:18:57.761349Z","end":"2026-08-28T13:18:57.864919Z","steps":["trace[2010646999] 'range keys from in-memory index tree' (duration: 103.185681ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:14.562528Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2957} {"level":"info","ts":"2026-08-28T13:20:14.651372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2957,"took":"87.052129ms","hash":46781960,"current-db-size-bytes":43356160,"current-db-size":"43 MB","current-db-size-in-use-bytes":18657280,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-28T13:20:14.65144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":46781960,"revision":2957,"compact-revision":-1} {"level":"info","ts":"2026-08-28T13:20:45.372244Z","caller":"traceutil/trace.go:171","msg":"trace[92308985] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"143.44335ms","start":"2026-08-28T13:20:45.228737Z","end":"2026-08-28T13:20:45.37218Z","steps":["trace[92308985] 'process raft request' (duration: 139.728897ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:45.375425Z","caller":"traceutil/trace.go:171","msg":"trace[501660047] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"123.80426ms","start":"2026-08-28T13:20:45.251533Z","end":"2026-08-28T13:20:45.375337Z","steps":["trace[501660047] 'process raft request' (duration: 123.274336ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:45.375941Z","caller":"traceutil/trace.go:171","msg":"trace[1386958212] transaction","detail":"{read_only:false; response_revision:5544; number_of_response:1; }","duration":"122.114527ms","start":"2026-08-28T13:20:45.253815Z","end":"2026-08-28T13:20:45.37593Z","steps":["trace[1386958212] 'process raft request' (duration: 121.45288ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:45.376116Z","caller":"traceutil/trace.go:171","msg":"trace[372054124] transaction","detail":"{read_only:false; response_revision:5546; number_of_response:1; }","duration":"121.750448ms","start":"2026-08-28T13:20:45.25432Z","end":"2026-08-28T13:20:45.376071Z","steps":["trace[372054124] 'process raft request' (duration: 121.354848ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:45.376143Z","caller":"traceutil/trace.go:171","msg":"trace[295626198] transaction","detail":"{read_only:false; response_revision:5545; number_of_response:1; }","duration":"121.981193ms","start":"2026-08-28T13:20:45.254122Z","end":"2026-08-28T13:20:45.376103Z","steps":["trace[295626198] 'process raft request' (duration: 121.42748ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:45.37629Z","caller":"traceutil/trace.go:171","msg":"trace[534145373] linearizableReadLoop","detail":"{readStateIndex:5766; appliedIndex:5756; }","duration":"120.137888ms","start":"2026-08-28T13:20:45.256118Z","end":"2026-08-28T13:20:45.376256Z","steps":["trace[534145373] 'read index received' (duration: 112.286841ms)","trace[534145373] 'applied index is now lower than readState.Index' (duration: 7.849837ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:20:45.376426Z","caller":"traceutil/trace.go:171","msg":"trace[1956851721] transaction","detail":"{read_only:false; response_revision:5548; number_of_response:1; }","duration":"121.709658ms","start":"2026-08-28T13:20:45.254706Z","end":"2026-08-28T13:20:45.376416Z","steps":["trace[1956851721] 'process raft request' (duration: 121.084782ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:45.376457Z","caller":"traceutil/trace.go:171","msg":"trace[217593387] transaction","detail":"{read_only:false; response_revision:5550; number_of_response:1; }","duration":"120.776714ms","start":"2026-08-28T13:20:45.255664Z","end":"2026-08-28T13:20:45.376441Z","steps":["trace[217593387] 'process raft request' (duration: 120.279362ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:45.376476Z","caller":"traceutil/trace.go:171","msg":"trace[1403793509] transaction","detail":"{read_only:false; response_revision:5547; number_of_response:1; }","duration":"121.80498ms","start":"2026-08-28T13:20:45.254646Z","end":"2026-08-28T13:20:45.37645Z","steps":["trace[1403793509] 'process raft request' (duration: 121.081811ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:45.376519Z","caller":"traceutil/trace.go:171","msg":"trace[160029812] transaction","detail":"{read_only:false; response_revision:5549; number_of_response:1; }","duration":"121.095772ms","start":"2026-08-28T13:20:45.255414Z","end":"2026-08-28T13:20:45.37651Z","steps":["trace[160029812] 'process raft request' (duration: 120.435515ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:45.376622Z","caller":"traceutil/trace.go:171","msg":"trace[208915386] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"120.846026ms","start":"2026-08-28T13:20:45.255768Z","end":"2026-08-28T13:20:45.376614Z","steps":["trace[208915386] 'process raft request' (duration: 120.320912ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:45.376811Z","caller":"traceutil/trace.go:171","msg":"trace[1738307716] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"120.445276ms","start":"2026-08-28T13:20:45.256343Z","end":"2026-08-28T13:20:45.376789Z","steps":["trace[1738307716] 'process raft request' (duration: 119.789329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:20:45.376905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.768773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/loki-gateway\" ","response":"range_response_count:1 size:4616"} {"level":"info","ts":"2026-08-28T13:20:45.37694Z","caller":"traceutil/trace.go:171","msg":"trace[834944764] range","detail":"{range_begin:/registry/deployments/monitoring/loki-gateway; range_end:; response_count:1; response_revision:5553; }","duration":"120.850695ms","start":"2026-08-28T13:20:45.256081Z","end":"2026-08-28T13:20:45.376931Z","steps":["trace[834944764] 'agreement among raft nodes before linearized reading' (duration: 120.693761ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:48.725165Z","caller":"traceutil/trace.go:171","msg":"trace[1367302560] linearizableReadLoop","detail":"{readStateIndex:5853; appliedIndex:5852; }","duration":"319.16261ms","start":"2026-08-28T13:20:48.405968Z","end":"2026-08-28T13:20:48.725131Z","steps":["trace[1367302560] 'read index received' (duration: 8.302237ms)","trace[1367302560] 'applied index is now lower than readState.Index' (duration: 310.858863ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:20:48.72532Z","caller":"traceutil/trace.go:171","msg":"trace[2095092666] transaction","detail":"{read_only:false; response_revision:5635; number_of_response:1; }","duration":"330.359361ms","start":"2026-08-28T13:20:48.394898Z","end":"2026-08-28T13:20:48.725257Z","steps":["trace[2095092666] 'process raft request' (duration: 330.019732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:20:48.72553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.51747ms","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":"warn","ts":"2026-08-28T13:20:48.725595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:20:48.394859Z","time spent":"330.626868ms","remote":"127.0.0.1:59648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4888,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T13:20:48.725649Z","caller":"traceutil/trace.go:171","msg":"trace[899650254] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:5635; }","duration":"319.711974ms","start":"2026-08-28T13:20:48.40591Z","end":"2026-08-28T13:20:48.725622Z","steps":["trace[899650254] 'agreement among raft nodes before linearized reading' (duration: 319.440748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:20:48.72565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.796697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-28T13:20:48.725701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:20:48.405896Z","time spent":"319.792686ms","remote":"127.0.0.1:58780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"info","ts":"2026-08-28T13:20:48.725723Z","caller":"traceutil/trace.go:171","msg":"trace[1145052891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5635; }","duration":"202.952071ms","start":"2026-08-28T13:20:48.522755Z","end":"2026-08-28T13:20:48.725707Z","steps":["trace[1145052891] 'agreement among raft nodes before linearized reading' (duration: 202.650644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:20:48.726318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.273486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787121"} {"level":"info","ts":"2026-08-28T13:20:48.726394Z","caller":"traceutil/trace.go:171","msg":"trace[1309555950] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5635; }","duration":"246.350178ms","start":"2026-08-28T13:20:48.480023Z","end":"2026-08-28T13:20:48.726373Z","steps":["trace[1309555950] 'agreement among raft nodes before linearized reading' (duration: 245.372254ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:59.402602Z","caller":"traceutil/trace.go:171","msg":"trace[1969713807] transaction","detail":"{read_only:false; response_revision:5816; number_of_response:1; }","duration":"103.146263ms","start":"2026-08-28T13:20:59.299412Z","end":"2026-08-28T13:20:59.402559Z","steps":["trace[1969713807] 'process raft request' (duration: 94.835845ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:59.487788Z","caller":"traceutil/trace.go:171","msg":"trace[719993339] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"185.044702ms","start":"2026-08-28T13:20:59.302684Z","end":"2026-08-28T13:20:59.487729Z","steps":["trace[719993339] 'process raft request' (duration: 184.675103ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:59.487878Z","caller":"traceutil/trace.go:171","msg":"trace[1734605663] transaction","detail":"{read_only:false; response_revision:5819; number_of_response:1; }","duration":"184.800577ms","start":"2026-08-28T13:20:59.303051Z","end":"2026-08-28T13:20:59.487851Z","steps":["trace[1734605663] 'process raft request' (duration: 184.607653ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:20:59.487862Z","caller":"traceutil/trace.go:171","msg":"trace[725589094] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"184.865218ms","start":"2026-08-28T13:20:59.302937Z","end":"2026-08-28T13:20:59.487802Z","steps":["trace[725589094] 'process raft request' (duration: 184.625222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:20:59.488022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.030072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:20:59.487815Z","caller":"traceutil/trace.go:171","msg":"trace[1169088621] linearizableReadLoop","detail":"{readStateIndex:6041; appliedIndex:6039; }","duration":"184.829038ms","start":"2026-08-28T13:20:59.302953Z","end":"2026-08-28T13:20:59.487782Z","steps":["trace[1169088621] 'read index received' (duration: 91.311736ms)","trace[1169088621] 'applied index is now lower than readState.Index' (duration: 93.515792ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:20:59.488089Z","caller":"traceutil/trace.go:171","msg":"trace[1493910682] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:5819; }","duration":"185.128005ms","start":"2026-08-28T13:20:59.302945Z","end":"2026-08-28T13:20:59.488073Z","steps":["trace[1493910682] 'agreement among raft nodes before linearized reading' (duration: 184.94315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:20:59.488405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.576586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-28T13:20:59.488473Z","caller":"traceutil/trace.go:171","msg":"trace[805216870] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5819; }","duration":"163.745151ms","start":"2026-08-28T13:20:59.324704Z","end":"2026-08-28T13:20:59.48845Z","steps":["trace[805216870] 'agreement among raft nodes before linearized reading' (duration: 163.453633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:21:04.87013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.917753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T13:21:04.870276Z","caller":"traceutil/trace.go:171","msg":"trace[1519539691] transaction","detail":"{read_only:false; response_revision:5898; number_of_response:1; }","duration":"154.970479ms","start":"2026-08-28T13:21:04.715289Z","end":"2026-08-28T13:21:04.870259Z","steps":["trace[1519539691] 'compare' (duration: 145.81261ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:21:04.937554Z","caller":"traceutil/trace.go:171","msg":"trace[72870755] transaction","detail":"{read_only:false; response_revision:5899; number_of_response:1; }","duration":"220.859649ms","start":"2026-08-28T13:21:04.716664Z","end":"2026-08-28T13:21:04.937524Z","steps":["trace[72870755] 'process raft request' (duration: 220.706275ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:21:05.041597Z","caller":"traceutil/trace.go:171","msg":"trace[1605329686] linearizableReadLoop","detail":"{readStateIndex:6126; appliedIndex:6124; }","duration":"169.74535ms","start":"2026-08-28T13:21:04.871818Z","end":"2026-08-28T13:21:05.041563Z","steps":["trace[1605329686] 'read index received' (duration: 65.51435ms)","trace[1605329686] 'applied index is now lower than readState.Index' (duration: 104.22964ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:21:05.041696Z","caller":"traceutil/trace.go:171","msg":"trace[1318912810] transaction","detail":"{read_only:false; response_revision:5900; number_of_response:1; }","duration":"289.430247ms","start":"2026-08-28T13:21:04.752211Z","end":"2026-08-28T13:21:05.041641Z","steps":["trace[1318912810] 'process raft request' (duration: 260.873531ms)","trace[1318912810] 'compare' (duration: 28.33129ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:21:05.041849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.007747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-28T13:21:05.041936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.240107ms","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-08-28T13:21:05.041951Z","caller":"traceutil/trace.go:171","msg":"trace[1815264562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5900; }","duration":"170.12603ms","start":"2026-08-28T13:21:04.871803Z","end":"2026-08-28T13:21:05.041929Z","steps":["trace[1815264562] 'agreement among raft nodes before linearized reading' (duration: 169.874474ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:21:05.042007Z","caller":"traceutil/trace.go:171","msg":"trace[242597650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5900; }","duration":"165.37634ms","start":"2026-08-28T13:21:04.876611Z","end":"2026-08-28T13:21:05.041987Z","steps":["trace[242597650] 'agreement among raft nodes before linearized reading' (duration: 165.152575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:21:05.042025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.944639ms","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-08-28T13:21:05.042122Z","caller":"traceutil/trace.go:171","msg":"trace[2002985500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5900; }","duration":"163.089703ms","start":"2026-08-28T13:21:04.879003Z","end":"2026-08-28T13:21:05.042093Z","steps":["trace[2002985500] 'agreement among raft nodes before linearized reading' (duration: 162.798936ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:21:12.102064Z","caller":"traceutil/trace.go:171","msg":"trace[1802548945] transaction","detail":"{read_only:false; response_revision:5998; number_of_response:1; }","duration":"159.252856ms","start":"2026-08-28T13:21:11.942767Z","end":"2026-08-28T13:21:12.10202Z","steps":["trace[1802548945] 'process raft request' (duration: 158.897198ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:21:12.102645Z","caller":"traceutil/trace.go:171","msg":"trace[699623920] transaction","detail":"{read_only:false; response_revision:5999; number_of_response:1; }","duration":"143.665447ms","start":"2026-08-28T13:21:11.958954Z","end":"2026-08-28T13:21:12.102619Z","steps":["trace[699623920] 'process raft request' (duration: 143.40161ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:21:12.102806Z","caller":"traceutil/trace.go:171","msg":"trace[525732560] transaction","detail":"{read_only:false; response_revision:6000; number_of_response:1; }","duration":"141.676476ms","start":"2026-08-28T13:21:11.96112Z","end":"2026-08-28T13:21:12.102796Z","steps":["trace[525732560] 'process raft request' (duration: 141.327338ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:21:15.617744Z","caller":"traceutil/trace.go:171","msg":"trace[81081237] transaction","detail":"{read_only:false; response_revision:6074; number_of_response:1; }","duration":"385.334444ms","start":"2026-08-28T13:21:15.23238Z","end":"2026-08-28T13:21:15.617714Z","steps":["trace[81081237] 'process raft request' (duration: 385.051547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:21:15.617941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:21:15.232345Z","time spent":"385.501858ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T13:21:15.799018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.302769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T13:21:15.799269Z","caller":"traceutil/trace.go:171","msg":"trace[87537731] linearizableReadLoop","detail":"{readStateIndex:6304; appliedIndex:6301; }","duration":"556.920178ms","start":"2026-08-28T13:21:15.24233Z","end":"2026-08-28T13:21:15.79925Z","steps":["trace[87537731] 'read index received' (duration: 375.197208ms)","trace[87537731] 'applied index is now lower than readState.Index' (duration: 181.72219ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:21:15.799326Z","caller":"traceutil/trace.go:171","msg":"trace[1667916031] transaction","detail":"{read_only:false; response_revision:6075; number_of_response:1; }","duration":"567.220928ms","start":"2026-08-28T13:21:15.232076Z","end":"2026-08-28T13:21:15.799297Z","steps":["trace[1667916031] 'process raft request' (duration: 449.541719ms)","trace[1667916031] 'compare' (duration: 117.078133ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:21:15.799349Z","caller":"traceutil/trace.go:171","msg":"trace[230792922] transaction","detail":"{read_only:false; response_revision:6076; number_of_response:1; }","duration":"566.539131ms","start":"2026-08-28T13:21:15.232781Z","end":"2026-08-28T13:21:15.79932Z","steps":["trace[230792922] 'process raft request' (duration: 566.347326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:21:15.799428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:21:15.232035Z","time spent":"567.346711ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T13:21:15.79948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.157513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-75nt9\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-08-28T13:21:15.799493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:21:15.23275Z","time spent":"566.661894ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T13:21:15.799508Z","caller":"traceutil/trace.go:171","msg":"trace[126903815] range","detail":"{range_begin:/registry/pods/openstack/keepalived-75nt9; range_end:; response_count:1; response_revision:6076; }","duration":"557.192194ms","start":"2026-08-28T13:21:15.242306Z","end":"2026-08-28T13:21:15.799498Z","steps":["trace[126903815] 'agreement among raft nodes before linearized reading' (duration: 557.04833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:21:15.79951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.893487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T13:21:15.799539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:21:15.242281Z","time spent":"557.250516ms","remote":"127.0.0.1:59648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-75nt9\" "} {"level":"info","ts":"2026-08-28T13:21:15.799564Z","caller":"traceutil/trace.go:171","msg":"trace[1487178398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6076; }","duration":"524.987628ms","start":"2026-08-28T13:21:15.27456Z","end":"2026-08-28T13:21:15.799547Z","steps":["trace[1487178398] 'agreement among raft nodes before linearized reading' (duration: 524.904146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:21:15.799596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:21:15.27454Z","time spent":"525.04907ms","remote":"127.0.0.1:59480","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-08-28T13:21:15.799763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.797035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc0c4ea7-a9d9-489e-9c5b-2237206c6f71\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-28T13:21:15.799892Z","caller":"traceutil/trace.go:171","msg":"trace[1855961464] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc0c4ea7-a9d9-489e-9c5b-2237206c6f71; range_end:; response_count:1; response_revision:6076; }","duration":"349.951759ms","start":"2026-08-28T13:21:15.449909Z","end":"2026-08-28T13:21:15.799861Z","steps":["trace[1855961464] 'agreement among raft nodes before linearized reading' (duration: 349.59736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:21:15.799961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:21:15.449835Z","time spent":"350.109382ms","remote":"127.0.0.1:59648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc0c4ea7-a9d9-489e-9c5b-2237206c6f71\" "} {"level":"warn","ts":"2026-08-28T13:21:15.799966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.990575ms","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-08-28T13:21:15.800062Z","caller":"traceutil/trace.go:171","msg":"trace[1743405234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6076; }","duration":"517.13264ms","start":"2026-08-28T13:21:15.282907Z","end":"2026-08-28T13:21:15.80004Z","steps":["trace[1743405234] 'agreement among raft nodes before linearized reading' (duration: 516.78027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:21:15.800111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:21:15.282897Z","time spent":"517.205982ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-28T13:21:16.104078Z","caller":"traceutil/trace.go:171","msg":"trace[507862100] linearizableReadLoop","detail":"{readStateIndex:6306; appliedIndex:6305; }","duration":"224.141034ms","start":"2026-08-28T13:21:15.879905Z","end":"2026-08-28T13:21:16.104046Z","steps":["trace[507862100] 'read index received' (duration: 223.929388ms)","trace[507862100] 'applied index is now lower than readState.Index' (duration: 210.165µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:21:16.104284Z","caller":"traceutil/trace.go:171","msg":"trace[689990389] transaction","detail":"{read_only:false; response_revision:6078; number_of_response:1; }","duration":"294.876745ms","start":"2026-08-28T13:21:15.809216Z","end":"2026-08-28T13:21:16.104093Z","steps":["trace[689990389] 'process raft request' (duration: 294.612048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:21:16.104412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.480971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-08-28T13:21:16.10466Z","caller":"traceutil/trace.go:171","msg":"trace[859092825] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:6078; }","duration":"224.706638ms","start":"2026-08-28T13:21:15.879853Z","end":"2026-08-28T13:21:16.104559Z","steps":["trace[859092825] 'agreement among raft nodes before linearized reading' (duration: 224.372729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:21:16.106449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.426049ms","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-08-28T13:21:16.106591Z","caller":"traceutil/trace.go:171","msg":"trace[1195586838] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6079; }","duration":"120.611882ms","start":"2026-08-28T13:21:15.985952Z","end":"2026-08-28T13:21:16.106564Z","steps":["trace[1195586838] 'agreement among raft nodes before linearized reading' (duration: 120.257293ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:21:16.106581Z","caller":"traceutil/trace.go:171","msg":"trace[1071890541] transaction","detail":"{read_only:false; response_revision:6079; number_of_response:1; }","duration":"179.932615ms","start":"2026-08-28T13:21:15.926616Z","end":"2026-08-28T13:21:16.106549Z","steps":["trace[1071890541] 'process raft request' (duration: 179.396021ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:21:28.382879Z","caller":"traceutil/trace.go:171","msg":"trace[810421784] linearizableReadLoop","detail":"{readStateIndex:6482; appliedIndex:6481; }","duration":"109.872681ms","start":"2026-08-28T13:21:28.272977Z","end":"2026-08-28T13:21:28.38285Z","steps":["trace[810421784] 'read index received' (duration: 61.299012ms)","trace[810421784] 'applied index is now lower than readState.Index' (duration: 48.572309ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:21:28.382973Z","caller":"traceutil/trace.go:171","msg":"trace[821774903] transaction","detail":"{read_only:false; response_revision:6249; number_of_response:1; }","duration":"110.851205ms","start":"2026-08-28T13:21:28.272063Z","end":"2026-08-28T13:21:28.382914Z","steps":["trace[821774903] 'process raft request' (duration: 62.349138ms)","trace[821774903] 'compare' (duration: 48.297263ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:21:28.383094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.094027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:21:28.383278Z","caller":"traceutil/trace.go:171","msg":"trace[1164257356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6249; }","duration":"110.347853ms","start":"2026-08-28T13:21:28.272902Z","end":"2026-08-28T13:21:28.38325Z","steps":["trace[1164257356] 'agreement among raft nodes before linearized reading' (duration: 110.113197ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:21:28.513012Z","caller":"traceutil/trace.go:171","msg":"trace[663548449] transaction","detail":"{read_only:false; response_revision:6251; number_of_response:1; }","duration":"101.397707ms","start":"2026-08-28T13:21:28.411576Z","end":"2026-08-28T13:21:28.512973Z","steps":["trace[663548449] 'process raft request' (duration: 77.133523ms)","trace[663548449] 'compare' (duration: 24.07315ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:25:14.569797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4808} {"level":"info","ts":"2026-08-28T13:25:14.629507Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4808,"took":"58.128063ms","hash":3810342120,"current-db-size-bytes":43356160,"current-db-size":"43 MB","current-db-size-in-use-bytes":38858752,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-08-28T13:25:14.62958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3810342120,"revision":4808,"compact-revision":2957} {"level":"info","ts":"2026-08-28T13:27:54.420102Z","caller":"traceutil/trace.go:171","msg":"trace[1992737668] linearizableReadLoop","detail":"{readStateIndex:9405; appliedIndex:9404; }","duration":"335.41811ms","start":"2026-08-28T13:27:54.084659Z","end":"2026-08-28T13:27:54.420077Z","steps":["trace[1992737668] 'read index received' (duration: 335.154692ms)","trace[1992737668] 'applied index is now lower than readState.Index' (duration: 262.588µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:27:54.420191Z","caller":"traceutil/trace.go:171","msg":"trace[651474846] transaction","detail":"{read_only:false; response_revision:9080; number_of_response:1; }","duration":"449.988662ms","start":"2026-08-28T13:27:53.970169Z","end":"2026-08-28T13:27:54.420157Z","steps":["trace[651474846] 'process raft request' (duration: 449.722724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:27:54.420333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:27:53.970151Z","time spent":"450.106205ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T13:27:54.420373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.700148ms","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-08-28T13:27:54.420421Z","caller":"traceutil/trace.go:171","msg":"trace[1368498385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9080; }","duration":"335.82502ms","start":"2026-08-28T13:27:54.08458Z","end":"2026-08-28T13:27:54.420405Z","steps":["trace[1368498385] 'agreement among raft nodes before linearized reading' (duration: 335.651466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:27:54.420531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:27:54.084553Z","time spent":"335.965104ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-28T13:27:54.420586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.247268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:27:54.420622Z","caller":"traceutil/trace.go:171","msg":"trace[993356632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9080; }","duration":"147.32624ms","start":"2026-08-28T13:27:54.273284Z","end":"2026-08-28T13:27:54.42061Z","steps":["trace[993356632] 'agreement among raft nodes before linearized reading' (duration: 147.265018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:27:54.420742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.342292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-28T13:27:54.420854Z","caller":"traceutil/trace.go:171","msg":"trace[223541698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9080; }","duration":"333.485136ms","start":"2026-08-28T13:27:54.087351Z","end":"2026-08-28T13:27:54.420836Z","steps":["trace[223541698] 'agreement among raft nodes before linearized reading' (duration: 333.185098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:27:54.420924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:27:54.087337Z","time spent":"333.575429ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-28T13:27:54.420834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.171711ms","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-08-28T13:27:54.421079Z","caller":"traceutil/trace.go:171","msg":"trace[1713355573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9080; }","duration":"214.492561ms","start":"2026-08-28T13:27:54.206561Z","end":"2026-08-28T13:27:54.421054Z","steps":["trace[1713355573] 'agreement among raft nodes before linearized reading' (duration: 214.058988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:27:54.42157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.820892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T13:27:54.42166Z","caller":"traceutil/trace.go:171","msg":"trace[215392179] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:9080; }","duration":"295.982577ms","start":"2026-08-28T13:27:54.125659Z","end":"2026-08-28T13:27:54.421641Z","steps":["trace[215392179] 'agreement among raft nodes before linearized reading' (duration: 294.98792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:27:54.700787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.170457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T13:27:54.700923Z","caller":"traceutil/trace.go:171","msg":"trace[1790801160] transaction","detail":"{read_only:false; response_revision:9081; number_of_response:1; }","duration":"275.18674ms","start":"2026-08-28T13:27:54.425715Z","end":"2026-08-28T13:27:54.700902Z","steps":["trace[1790801160] 'process raft request' (duration: 93.794387ms)","trace[1790801160] 'compare' (duration: 180.732365ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:27:54.710743Z","caller":"traceutil/trace.go:171","msg":"trace[76399421] linearizableReadLoop","detail":"{readStateIndex:9408; appliedIndex:9405; }","duration":"284.355515ms","start":"2026-08-28T13:27:54.426364Z","end":"2026-08-28T13:27:54.71072Z","steps":["trace[76399421] 'read index received' (duration: 93.15806ms)","trace[76399421] 'applied index is now lower than readState.Index' (duration: 191.196295ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:27:54.7108Z","caller":"traceutil/trace.go:171","msg":"trace[230681412] transaction","detail":"{read_only:false; response_revision:9082; number_of_response:1; }","duration":"284.55647ms","start":"2026-08-28T13:27:54.426209Z","end":"2026-08-28T13:27:54.710766Z","steps":["trace[230681412] 'process raft request' (duration: 284.227122ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:27:54.710869Z","caller":"traceutil/trace.go:171","msg":"trace[879037299] transaction","detail":"{read_only:false; response_revision:9083; number_of_response:1; }","duration":"284.630123ms","start":"2026-08-28T13:27:54.426226Z","end":"2026-08-28T13:27:54.710856Z","steps":["trace[879037299] 'process raft request' (duration: 284.445758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:27:54.711577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.206798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224091"} {"level":"info","ts":"2026-08-28T13:27:54.711686Z","caller":"traceutil/trace.go:171","msg":"trace[1819904906] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:9083; }","duration":"285.360072ms","start":"2026-08-28T13:27:54.426305Z","end":"2026-08-28T13:27:54.711665Z","steps":["trace[1819904906] 'agreement among raft nodes before linearized reading' (duration: 284.670573ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:28:04.738541Z","caller":"traceutil/trace.go:171","msg":"trace[357111172] transaction","detail":"{read_only:false; response_revision:9131; number_of_response:1; }","duration":"264.905106ms","start":"2026-08-28T13:28:04.473587Z","end":"2026-08-28T13:28:04.738492Z","steps":["trace[357111172] 'process raft request' (duration: 264.750172ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:28:05.431874Z","caller":"traceutil/trace.go:171","msg":"trace[1184916141] transaction","detail":"{read_only:false; response_revision:9136; number_of_response:1; }","duration":"144.505882ms","start":"2026-08-28T13:28:05.287325Z","end":"2026-08-28T13:28:05.431831Z","steps":["trace[1184916141] 'process raft request' (duration: 144.251654ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:28:06.00457Z","caller":"traceutil/trace.go:171","msg":"trace[175978168] transaction","detail":"{read_only:false; response_revision:9138; number_of_response:1; }","duration":"114.970704ms","start":"2026-08-28T13:28:05.889569Z","end":"2026-08-28T13:28:06.00454Z","steps":["trace[175978168] 'process raft request' (duration: 114.583043ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:28:06.902126Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002500.wal"} {"level":"info","ts":"2026-08-28T13:28:38.56595Z","caller":"traceutil/trace.go:171","msg":"trace[319663878] transaction","detail":"{read_only:false; response_revision:9365; number_of_response:1; }","duration":"431.4321ms","start":"2026-08-28T13:28:38.13448Z","end":"2026-08-28T13:28:38.565912Z","steps":["trace[319663878] 'process raft request' (duration: 431.05402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:28:38.566242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:28:38.134458Z","time spent":"431.641137ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T13:28:38.576481Z","caller":"traceutil/trace.go:171","msg":"trace[1407106653] transaction","detail":"{read_only:false; response_revision:9367; number_of_response:1; }","duration":"424.473241ms","start":"2026-08-28T13:28:38.151979Z","end":"2026-08-28T13:28:38.576453Z","steps":["trace[1407106653] 'process raft request' (duration: 424.362348ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:28:38.576545Z","caller":"traceutil/trace.go:171","msg":"trace[1188075735] transaction","detail":"{read_only:false; response_revision:9366; number_of_response:1; }","duration":"430.044485ms","start":"2026-08-28T13:28:38.146461Z","end":"2026-08-28T13:28:38.576505Z","steps":["trace[1188075735] 'process raft request' (duration: 429.712567ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:28:38.576648Z","caller":"traceutil/trace.go:171","msg":"trace[155549435] linearizableReadLoop","detail":"{readStateIndex:9704; appliedIndex:9700; }","duration":"303.681584ms","start":"2026-08-28T13:28:38.272948Z","end":"2026-08-28T13:28:38.576629Z","steps":["trace[155549435] 'read index received' (duration: 292.460305ms)","trace[155549435] 'applied index is now lower than readState.Index' (duration: 11.219799ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:28:38.576673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:28:38.151934Z","time spent":"424.634495ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T13:28:38.576724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:28:38.146413Z","time spent":"430.216479ms","remote":"127.0.0.1:59738","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-08-28T13:28:38.576896Z","caller":"traceutil/trace.go:171","msg":"trace[213135234] transaction","detail":"{read_only:false; response_revision:9368; number_of_response:1; }","duration":"414.856363ms","start":"2026-08-28T13:28:38.162028Z","end":"2026-08-28T13:28:38.576884Z","steps":["trace[213135234] 'process raft request' (duration: 414.384231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:28:38.576939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.212534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.14\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-28T13:28:38.576979Z","caller":"traceutil/trace.go:171","msg":"trace[855350819] range","detail":"{range_begin:/registry/masterleases/162.253.55.14; range_end:; response_count:1; response_revision:9368; }","duration":"205.309476ms","start":"2026-08-28T13:28:38.371658Z","end":"2026-08-28T13:28:38.576968Z","steps":["trace[855350819] 'agreement among raft nodes before linearized reading' (duration: 205.178883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:28:38.576988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:28:38.162Z","time spent":"414.929335ms","remote":"127.0.0.1:59738","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-08-28T13:28:38.577001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.052994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:28:38.577116Z","caller":"traceutil/trace.go:171","msg":"trace[746460337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9368; }","duration":"304.211179ms","start":"2026-08-28T13:28:38.272883Z","end":"2026-08-28T13:28:38.577094Z","steps":["trace[746460337] 'agreement among raft nodes before linearized reading' (duration: 304.045614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:28:38.577168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:28:38.27286Z","time spent":"304.299931ms","remote":"127.0.0.1:59480","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-08-28T13:28:49.167076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.173457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-28T13:28:49.167178Z","caller":"traceutil/trace.go:171","msg":"trace[534316563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9423; }","duration":"178.416023ms","start":"2026-08-28T13:28:48.988739Z","end":"2026-08-28T13:28:49.167155Z","steps":["trace[534316563] 'range keys from in-memory index tree' (duration: 177.863669ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:28:53.08955Z","caller":"traceutil/trace.go:171","msg":"trace[637964408] linearizableReadLoop","detail":"{readStateIndex:9798; appliedIndex:9797; }","duration":"163.226272ms","start":"2026-08-28T13:28:52.926297Z","end":"2026-08-28T13:28:53.089523Z","steps":["trace[637964408] 'read index received' (duration: 162.965896ms)","trace[637964408] 'applied index is now lower than readState.Index' (duration: 258.486µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:28:53.089895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.588824ms","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-08-28T13:28:53.089983Z","caller":"traceutil/trace.go:171","msg":"trace[1757631054] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9459; }","duration":"163.737987ms","start":"2026-08-28T13:28:52.926231Z","end":"2026-08-28T13:28:53.089969Z","steps":["trace[1757631054] 'agreement among raft nodes before linearized reading' (duration: 163.499492ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:28:53.090405Z","caller":"traceutil/trace.go:171","msg":"trace[675670065] transaction","detail":"{read_only:false; response_revision:9459; number_of_response:1; }","duration":"198.934349ms","start":"2026-08-28T13:28:52.891457Z","end":"2026-08-28T13:28:53.090391Z","steps":["trace[675670065] 'process raft request' (duration: 197.888452ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:29:02.835812Z","caller":"traceutil/trace.go:171","msg":"trace[1369869347] transaction","detail":"{read_only:false; response_revision:9572; number_of_response:1; }","duration":"123.26002ms","start":"2026-08-28T13:29:02.712527Z","end":"2026-08-28T13:29:02.835787Z","steps":["trace[1369869347] 'process raft request' (duration: 91.986538ms)","trace[1369869347] 'compare' (duration: 31.084987ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:29:02.835996Z","caller":"traceutil/trace.go:171","msg":"trace[108650619] transaction","detail":"{read_only:false; response_revision:9573; number_of_response:1; }","duration":"118.627961ms","start":"2026-08-28T13:29:02.717333Z","end":"2026-08-28T13:29:02.835961Z","steps":["trace[108650619] 'process raft request' (duration: 118.408846ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:29:13.371951Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":10003,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T13:29:13.379741Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10003} {"level":"info","ts":"2026-08-28T13:29:13.379897Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5003} {"level":"info","ts":"2026-08-28T13:30:14.577176Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7827} {"level":"info","ts":"2026-08-28T13:30:14.680832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7827,"took":"100.136114ms","hash":1786108237,"current-db-size-bytes":43356160,"current-db-size":"43 MB","current-db-size-in-use-bytes":25337856,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-28T13:30:14.680916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1786108237,"revision":7827,"compact-revision":4808} {"level":"info","ts":"2026-08-28T13:30:25.138271Z","caller":"traceutil/trace.go:171","msg":"trace[1595749933] transaction","detail":"{read_only:false; response_revision:10318; number_of_response:1; }","duration":"109.689582ms","start":"2026-08-28T13:30:25.028533Z","end":"2026-08-28T13:30:25.138223Z","steps":["trace[1595749933] 'process raft request' (duration: 91.77626ms)","trace[1595749933] 'compare' (duration: 17.766889ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:30:25.169965Z","caller":"traceutil/trace.go:171","msg":"trace[1216865747] transaction","detail":"{read_only:false; response_revision:10319; number_of_response:1; }","duration":"136.229527ms","start":"2026-08-28T13:30:25.033701Z","end":"2026-08-28T13:30:25.16993Z","steps":["trace[1216865747] 'process raft request' (duration: 136.048312ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:30:25.508944Z","caller":"traceutil/trace.go:171","msg":"trace[1842464797] transaction","detail":"{read_only:false; response_revision:10320; number_of_response:1; }","duration":"315.183147ms","start":"2026-08-28T13:30:25.193718Z","end":"2026-08-28T13:30:25.508901Z","steps":["trace[1842464797] 'process raft request' (duration: 264.427606ms)","trace[1842464797] 'compare' (duration: 50.437522ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:30:25.509233Z","caller":"traceutil/trace.go:171","msg":"trace[966836059] linearizableReadLoop","detail":"{readStateIndex:10687; appliedIndex:10685; }","duration":"235.722594ms","start":"2026-08-28T13:30:25.273478Z","end":"2026-08-28T13:30:25.5092Z","steps":["trace[966836059] 'read index received' (duration: 184.619855ms)","trace[966836059] 'applied index is now lower than readState.Index' (duration: 51.100629ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:30:25.509307Z","caller":"traceutil/trace.go:171","msg":"trace[898063201] transaction","detail":"{read_only:false; response_revision:10321; number_of_response:1; }","duration":"303.847863ms","start":"2026-08-28T13:30:25.205402Z","end":"2026-08-28T13:30:25.50925Z","steps":["trace[898063201] 'process raft request' (duration: 303.418703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:30:25.509482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.964291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:30:25.509566Z","caller":"traceutil/trace.go:171","msg":"trace[684024254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10321; }","duration":"236.138254ms","start":"2026-08-28T13:30:25.273405Z","end":"2026-08-28T13:30:25.509543Z","steps":["trace[684024254] 'agreement among raft nodes before linearized reading' (duration: 235.899799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:30:25.509606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:30:25.205368Z","time spent":"304.066659ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T13:30:25.510267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:30:25.193674Z","time spent":"315.573136ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T13:35:14.584501Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10259} {"level":"info","ts":"2026-08-28T13:35:14.677872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10259,"took":"90.903267ms","hash":2612507958,"current-db-size-bytes":43356160,"current-db-size":"43 MB","current-db-size-in-use-bytes":25198592,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-28T13:35:14.67796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2612507958,"revision":10259,"compact-revision":7827} {"level":"info","ts":"2026-08-28T13:36:13.681847Z","caller":"traceutil/trace.go:171","msg":"trace[1536772238] linearizableReadLoop","detail":"{readStateIndex:13249; appliedIndex:13248; }","duration":"183.62764ms","start":"2026-08-28T13:36:13.498164Z","end":"2026-08-28T13:36:13.681791Z","steps":["trace[1536772238] 'read index received' (duration: 109.39324ms)","trace[1536772238] 'applied index is now lower than readState.Index' (duration: 74.2313ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:36:13.682224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.001751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T13:36:13.682282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.961369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:36:13.682313Z","caller":"traceutil/trace.go:171","msg":"trace[853392848] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12802; }","duration":"184.158354ms","start":"2026-08-28T13:36:13.498126Z","end":"2026-08-28T13:36:13.682285Z","steps":["trace[853392848] 'agreement among raft nodes before linearized reading' (duration: 183.883027ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:36:13.682325Z","caller":"traceutil/trace.go:171","msg":"trace[212550218] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12802; }","duration":"184.02972ms","start":"2026-08-28T13:36:13.49828Z","end":"2026-08-28T13:36:13.682309Z","steps":["trace[212550218] 'agreement among raft nodes before linearized reading' (duration: 183.949348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:36:13.682836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.472172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:36:13.682895Z","caller":"traceutil/trace.go:171","msg":"trace[544672848] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12802; }","duration":"184.589564ms","start":"2026-08-28T13:36:13.49829Z","end":"2026-08-28T13:36:13.68288Z","steps":["trace[544672848] 'agreement among raft nodes before linearized reading' (duration: 184.498322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:36:13.683044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.421275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-08-28T13:36:13.683034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.513773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T13:36:13.683376Z","caller":"traceutil/trace.go:171","msg":"trace[1742758362] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12802; }","duration":"185.058487ms","start":"2026-08-28T13:36:13.498291Z","end":"2026-08-28T13:36:13.68335Z","steps":["trace[1742758362] 'agreement among raft nodes before linearized reading' (duration: 184.455511ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:36:13.683909Z","caller":"traceutil/trace.go:171","msg":"trace[447135560] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12802; }","duration":"181.668911ms","start":"2026-08-28T13:36:13.501581Z","end":"2026-08-28T13:36:13.68325Z","steps":["trace[447135560] 'agreement among raft nodes before linearized reading' (duration: 181.203809ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:36:13.978274Z","caller":"traceutil/trace.go:171","msg":"trace[528932707] linearizableReadLoop","detail":"{readStateIndex:13251; appliedIndex:13249; }","duration":"128.412188ms","start":"2026-08-28T13:36:13.849821Z","end":"2026-08-28T13:36:13.978233Z","steps":["trace[528932707] 'read index received' (duration: 88.955918ms)","trace[528932707] 'applied index is now lower than readState.Index' (duration: 39.45413ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:36:13.97859Z","caller":"traceutil/trace.go:171","msg":"trace[28910895] transaction","detail":"{read_only:false; response_revision:12804; number_of_response:1; }","duration":"130.711856ms","start":"2026-08-28T13:36:13.847844Z","end":"2026-08-28T13:36:13.978555Z","steps":["trace[28910895] 'process raft request' (duration: 130.110071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:36:13.978737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.855168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-28T13:36:13.978808Z","caller":"traceutil/trace.go:171","msg":"trace[1131499326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12804; }","duration":"128.988151ms","start":"2026-08-28T13:36:13.849797Z","end":"2026-08-28T13:36:13.978786Z","steps":["trace[1131499326] 'agreement among raft nodes before linearized reading' (duration: 128.652053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:36:13.979116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.739822ms","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-08-28T13:36:13.979159Z","caller":"traceutil/trace.go:171","msg":"trace[63668194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12804; }","duration":"120.802943ms","start":"2026-08-28T13:36:13.858339Z","end":"2026-08-28T13:36:13.979142Z","steps":["trace[63668194] 'agreement among raft nodes before linearized reading' (duration: 120.66151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:36:13.979328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.366296ms","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-08-28T13:36:13.979366Z","caller":"traceutil/trace.go:171","msg":"trace[1208121625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12804; }","duration":"128.431678ms","start":"2026-08-28T13:36:13.850921Z","end":"2026-08-28T13:36:13.979353Z","steps":["trace[1208121625] 'agreement among raft nodes before linearized reading' (duration: 128.033378ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:40:14.59193Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12282} {"level":"info","ts":"2026-08-28T13:40:14.674833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12282,"took":"79.920569ms","hash":1167241261,"current-db-size-bytes":43356160,"current-db-size":"43 MB","current-db-size-in-use-bytes":26931200,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-28T13:40:14.674917Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1167241261,"revision":12282,"compact-revision":10259} {"level":"info","ts":"2026-08-28T13:43:06.05625Z","caller":"traceutil/trace.go:171","msg":"trace[1701532541] transaction","detail":"{read_only:false; response_revision:15530; number_of_response:1; }","duration":"218.612221ms","start":"2026-08-28T13:43:05.837597Z","end":"2026-08-28T13:43:06.056209Z","steps":["trace[1701532541] 'process raft request' (duration: 218.382095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:43:16.562729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.155326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-08-28T13:43:16.562826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.866746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:118644"} {"level":"info","ts":"2026-08-28T13:43:16.562869Z","caller":"traceutil/trace.go:171","msg":"trace[2103381452] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:15703; }","duration":"121.356081ms","start":"2026-08-28T13:43:16.441488Z","end":"2026-08-28T13:43:16.562844Z","steps":["trace[2103381452] 'range keys from in-memory index tree' (duration: 120.960491ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:43:16.56294Z","caller":"traceutil/trace.go:171","msg":"trace[2092776986] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:15703; }","duration":"105.049481ms","start":"2026-08-28T13:43:16.45787Z","end":"2026-08-28T13:43:16.562919Z","steps":["trace[2092776986] 'range keys from in-memory index tree' (duration: 104.62916ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:43:16.651586Z","caller":"traceutil/trace.go:171","msg":"trace[1784360623] linearizableReadLoop","detail":"{readStateIndex:16253; appliedIndex:16251; }","duration":"129.442828ms","start":"2026-08-28T13:43:16.5221Z","end":"2026-08-28T13:43:16.651543Z","steps":["trace[1784360623] 'read index received' (duration: 56.244836ms)","trace[1784360623] 'applied index is now lower than readState.Index' (duration: 73.196511ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:43:16.651664Z","caller":"traceutil/trace.go:171","msg":"trace[1573093062] transaction","detail":"{read_only:false; response_revision:15705; number_of_response:1; }","duration":"149.205667ms","start":"2026-08-28T13:43:16.50242Z","end":"2026-08-28T13:43:16.651625Z","steps":["trace[1573093062] 'process raft request' (duration: 148.94458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:43:16.651912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.903305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-28T13:43:16.651917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.761697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-28T13:43:16.652006Z","caller":"traceutil/trace.go:171","msg":"trace[427557439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15705; }","duration":"129.937152ms","start":"2026-08-28T13:43:16.522048Z","end":"2026-08-28T13:43:16.651985Z","steps":["trace[427557439] 'agreement among raft nodes before linearized reading' (duration: 129.619444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:43:16.652061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.038168ms","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-08-28T13:43:16.652095Z","caller":"traceutil/trace.go:171","msg":"trace[708597319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15705; }","duration":"108.08389ms","start":"2026-08-28T13:43:16.543997Z","end":"2026-08-28T13:43:16.652081Z","steps":["trace[708597319] 'agreement among raft nodes before linearized reading' (duration: 107.982067ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:43:16.651958Z","caller":"traceutil/trace.go:171","msg":"trace[763166045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15705; }","duration":"121.991267ms","start":"2026-08-28T13:43:16.529951Z","end":"2026-08-28T13:43:16.651943Z","steps":["trace[763166045] 'agreement among raft nodes before linearized reading' (duration: 121.785701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:43:16.65229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.635927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-28T13:43:16.65232Z","caller":"traceutil/trace.go:171","msg":"trace[1834977278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15705; }","duration":"112.716998ms","start":"2026-08-28T13:43:16.539595Z","end":"2026-08-28T13:43:16.652313Z","steps":["trace[1834977278] 'agreement among raft nodes before linearized reading' (duration: 112.633196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:43:16.652299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.202673ms","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-08-28T13:43:16.652397Z","caller":"traceutil/trace.go:171","msg":"trace[190128058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15705; }","duration":"110.371868ms","start":"2026-08-28T13:43:16.541998Z","end":"2026-08-28T13:43:16.65237Z","steps":["trace[190128058] 'agreement among raft nodes before linearized reading' (duration: 110.0659ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:43:16.78208Z","caller":"traceutil/trace.go:171","msg":"trace[1778433968] transaction","detail":"{read_only:false; response_revision:15706; number_of_response:1; }","duration":"124.194524ms","start":"2026-08-28T13:43:16.657857Z","end":"2026-08-28T13:43:16.782051Z","steps":["trace[1778433968] 'process raft request' (duration: 123.071895ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:43:16.946108Z","caller":"traceutil/trace.go:171","msg":"trace[1361425485] transaction","detail":"{read_only:false; response_revision:15707; number_of_response:1; }","duration":"287.571114ms","start":"2026-08-28T13:43:16.658481Z","end":"2026-08-28T13:43:16.946053Z","steps":["trace[1361425485] 'process raft request' (duration: 286.052064ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:43:16.946165Z","caller":"traceutil/trace.go:171","msg":"trace[1212088667] transaction","detail":"{read_only:false; response_revision:15710; number_of_response:1; }","duration":"285.999195ms","start":"2026-08-28T13:43:16.660131Z","end":"2026-08-28T13:43:16.94613Z","steps":["trace[1212088667] 'process raft request' (duration: 285.933993ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:43:16.94619Z","caller":"traceutil/trace.go:171","msg":"trace[136546762] transaction","detail":"{read_only:false; response_revision:15709; number_of_response:1; }","duration":"287.41465ms","start":"2026-08-28T13:43:16.65873Z","end":"2026-08-28T13:43:16.946145Z","steps":["trace[136546762] 'process raft request' (duration: 287.290287ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:43:16.946317Z","caller":"traceutil/trace.go:171","msg":"trace[1489243599] linearizableReadLoop","detail":"{readStateIndex:16257; appliedIndex:16253; }","duration":"287.076532ms","start":"2026-08-28T13:43:16.659219Z","end":"2026-08-28T13:43:16.946296Z","steps":["trace[1489243599] 'read index received' (duration: 121.72654ms)","trace[1489243599] 'applied index is now lower than readState.Index' (duration: 165.347952ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:43:16.946385Z","caller":"traceutil/trace.go:171","msg":"trace[68610534] transaction","detail":"{read_only:false; response_revision:15708; number_of_response:1; }","duration":"287.727398ms","start":"2026-08-28T13:43:16.658645Z","end":"2026-08-28T13:43:16.946373Z","steps":["trace[68610534] 'process raft request' (duration: 287.227384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:43:16.946658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.41687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-08-28T13:43:16.946695Z","caller":"traceutil/trace.go:171","msg":"trace[571590692] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:15712; }","duration":"287.496672ms","start":"2026-08-28T13:43:16.659187Z","end":"2026-08-28T13:43:16.946683Z","steps":["trace[571590692] 'agreement among raft nodes before linearized reading' (duration: 287.286117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:43:16.946786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.228685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:118644"} {"level":"info","ts":"2026-08-28T13:43:16.946889Z","caller":"traceutil/trace.go:171","msg":"trace[362065961] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:15712; }","duration":"287.358609ms","start":"2026-08-28T13:43:16.659505Z","end":"2026-08-28T13:43:16.946864Z","steps":["trace[362065961] 'agreement among raft nodes before linearized reading' (duration: 287.0651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:43:16.946798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.451025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-7zjjc.18cffbf25177135a\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-08-28T13:43:16.947056Z","caller":"traceutil/trace.go:171","msg":"trace[838812190] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-7zjjc.18cffbf25177135a; range_end:; response_count:1; response_revision:15712; }","duration":"153.776114ms","start":"2026-08-28T13:43:16.793262Z","end":"2026-08-28T13:43:16.947039Z","steps":["trace[838812190] 'agreement among raft nodes before linearized reading' (duration: 153.360073ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:44:43.128404Z","caller":"traceutil/trace.go:171","msg":"trace[1719493490] transaction","detail":"{read_only:false; response_revision:16722; number_of_response:1; }","duration":"108.028587ms","start":"2026-08-28T13:44:43.020332Z","end":"2026-08-28T13:44:43.12836Z","steps":["trace[1719493490] 'process raft request' (duration: 100.134825ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:44:48.643327Z","caller":"traceutil/trace.go:171","msg":"trace[1380495416] linearizableReadLoop","detail":"{readStateIndex:17328; appliedIndex:17327; }","duration":"118.055126ms","start":"2026-08-28T13:44:48.52525Z","end":"2026-08-28T13:44:48.643306Z","steps":["trace[1380495416] 'read index received' (duration: 84.77814ms)","trace[1380495416] 'applied index is now lower than readState.Index' (duration: 33.275896ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:44:48.643472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.2074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T13:44:48.643499Z","caller":"traceutil/trace.go:171","msg":"trace[1137136452] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16749; }","duration":"118.282242ms","start":"2026-08-28T13:44:48.525208Z","end":"2026-08-28T13:44:48.64349Z","steps":["trace[1137136452] 'agreement among raft nodes before linearized reading' (duration: 118.21316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:48.643772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.835329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-28T13:44:48.64376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.407385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:684"} {"level":"warn","ts":"2026-08-28T13:44:48.643846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.395354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-08-28T13:44:48.643843Z","caller":"traceutil/trace.go:171","msg":"trace[638168881] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16749; }","duration":"118.533708ms","start":"2026-08-28T13:44:48.525288Z","end":"2026-08-28T13:44:48.643822Z","steps":["trace[638168881] 'agreement among raft nodes before linearized reading' (duration: 118.244511ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:44:48.64387Z","caller":"traceutil/trace.go:171","msg":"trace[2130553280] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16749; }","duration":"118.484137ms","start":"2026-08-28T13:44:48.525378Z","end":"2026-08-28T13:44:48.643862Z","steps":["trace[2130553280] 'agreement among raft nodes before linearized reading' (duration: 118.438696ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:44:48.643797Z","caller":"traceutil/trace.go:171","msg":"trace[140452447] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16749; }","duration":"117.913022ms","start":"2026-08-28T13:44:48.525878Z","end":"2026-08-28T13:44:48.643791Z","steps":["trace[140452447] 'agreement among raft nodes before linearized reading' (duration: 117.867051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:48.643943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.938853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5524"} {"level":"info","ts":"2026-08-28T13:44:48.644051Z","caller":"traceutil/trace.go:171","msg":"trace[722422908] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16749; }","duration":"118.068556ms","start":"2026-08-28T13:44:48.525954Z","end":"2026-08-28T13:44:48.644022Z","steps":["trace[722422908] 'agreement among raft nodes before linearized reading' (duration: 117.769978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.585346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.008718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-08-28T13:44:49.585457Z","caller":"traceutil/trace.go:171","msg":"trace[292519095] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16752; }","duration":"278.394738ms","start":"2026-08-28T13:44:49.307038Z","end":"2026-08-28T13:44:49.585433Z","steps":["trace[292519095] 'range keys from in-memory index tree' (duration: 277.731152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.585509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.226889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T13:44:49.585669Z","caller":"traceutil/trace.go:171","msg":"trace[1510507434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16752; }","duration":"312.428054ms","start":"2026-08-28T13:44:49.273208Z","end":"2026-08-28T13:44:49.585636Z","steps":["trace[1510507434] 'range keys from in-memory index tree' (duration: 312.126187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.585734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:44:49.273128Z","time spent":"312.594978ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-28T13:44:49.585755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.056873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-08-28T13:44:49.585867Z","caller":"traceutil/trace.go:171","msg":"trace[254874213] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16752; }","duration":"289.225357ms","start":"2026-08-28T13:44:49.296619Z","end":"2026-08-28T13:44:49.585844Z","steps":["trace[254874213] 'range keys from in-memory index tree' (duration: 279.056166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.586331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.062703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-28T13:44:49.58655Z","caller":"traceutil/trace.go:171","msg":"trace[1679715930] linearizableReadLoop","detail":"{readStateIndex:17332; appliedIndex:17331; }","duration":"274.969441ms","start":"2026-08-28T13:44:49.311568Z","end":"2026-08-28T13:44:49.586537Z","steps":["trace[1679715930] 'read index received' (duration: 41.433716ms)","trace[1679715930] 'applied index is now lower than readState.Index' (duration: 233.534735ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:44:49.586662Z","caller":"traceutil/trace.go:171","msg":"trace[1821293551] transaction","detail":"{read_only:false; response_revision:16753; number_of_response:1; }","duration":"275.213877ms","start":"2026-08-28T13:44:49.311435Z","end":"2026-08-28T13:44:49.586649Z","steps":["trace[1821293551] 'process raft request' (duration: 41.740493ms)","trace[1821293551] 'compare' (duration: 232.342855ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:44:49.586919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.280358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5524"} {"level":"info","ts":"2026-08-28T13:44:49.586947Z","caller":"traceutil/trace.go:171","msg":"trace[1429922307] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16754; }","duration":"275.32411ms","start":"2026-08-28T13:44:49.311612Z","end":"2026-08-28T13:44:49.586936Z","steps":["trace[1429922307] 'agreement among raft nodes before linearized reading' (duration: 275.180526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.587615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.781692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5524"} {"level":"info","ts":"2026-08-28T13:44:49.587724Z","caller":"traceutil/trace.go:171","msg":"trace[585813707] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16754; }","duration":"193.885986ms","start":"2026-08-28T13:44:49.393788Z","end":"2026-08-28T13:44:49.587674Z","steps":["trace[585813707] 'agreement among raft nodes before linearized reading' (duration: 193.64598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.587911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.412162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T13:44:49.587948Z","caller":"traceutil/trace.go:171","msg":"trace[333321721] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16754; }","duration":"100.496574ms","start":"2026-08-28T13:44:49.487442Z","end":"2026-08-28T13:44:49.587938Z","steps":["trace[333321721] 'agreement among raft nodes before linearized reading' (duration: 100.422272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.588092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.197245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-28T13:44:49.588132Z","caller":"traceutil/trace.go:171","msg":"trace[1180873847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16754; }","duration":"147.254217ms","start":"2026-08-28T13:44:49.440864Z","end":"2026-08-28T13:44:49.588118Z","steps":["trace[1180873847] 'agreement among raft nodes before linearized reading' (duration: 147.155375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.588204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.778455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-28T13:44:49.588325Z","caller":"traceutil/trace.go:171","msg":"trace[182056369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16754; }","duration":"251.960309ms","start":"2026-08-28T13:44:49.336332Z","end":"2026-08-28T13:44:49.588293Z","steps":["trace[182056369] 'agreement among raft nodes before linearized reading' (duration: 251.673322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.588422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.210912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-28T13:44:49.588468Z","caller":"traceutil/trace.go:171","msg":"trace[885635208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16754; }","duration":"171.304834ms","start":"2026-08-28T13:44:49.41715Z","end":"2026-08-28T13:44:49.588455Z","steps":["trace[885635208] 'agreement among raft nodes before linearized reading' (duration: 171.173811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.588564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.657301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T13:44:49.588603Z","caller":"traceutil/trace.go:171","msg":"trace[328179912] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16754; }","duration":"195.748144ms","start":"2026-08-28T13:44:49.392845Z","end":"2026-08-28T13:44:49.588593Z","steps":["trace[328179912] 'agreement among raft nodes before linearized reading' (duration: 195.684632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.588669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.841653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-08-28T13:44:49.58873Z","caller":"traceutil/trace.go:171","msg":"trace[1074813321] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16754; }","duration":"198.937475ms","start":"2026-08-28T13:44:49.389778Z","end":"2026-08-28T13:44:49.588716Z","steps":["trace[1074813321] 'agreement among raft nodes before linearized reading' (duration: 198.810162ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:44:49.588929Z","caller":"traceutil/trace.go:171","msg":"trace[576518418] transaction","detail":"{read_only:false; response_revision:16754; number_of_response:1; }","duration":"277.300711ms","start":"2026-08-28T13:44:49.311613Z","end":"2026-08-28T13:44:49.588914Z","steps":["trace[576518418] 'process raft request' (duration: 274.820668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.589114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.542177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T13:44:49.58915Z","caller":"traceutil/trace.go:171","msg":"trace[898828900] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16754; }","duration":"277.618619ms","start":"2026-08-28T13:44:49.311521Z","end":"2026-08-28T13:44:49.589139Z","steps":["trace[898828900] 'agreement among raft nodes before linearized reading' (duration: 275.219967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:44:49.589571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.089559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T13:44:49.58962Z","caller":"traceutil/trace.go:171","msg":"trace[1023298303] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16754; }","duration":"101.193552ms","start":"2026-08-28T13:44:49.488415Z","end":"2026-08-28T13:44:49.589608Z","steps":["trace[1023298303] 'agreement among raft nodes before linearized reading' (duration: 101.12229ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:44:49.837017Z","caller":"traceutil/trace.go:171","msg":"trace[343572243] transaction","detail":"{read_only:false; response_revision:16758; number_of_response:1; }","duration":"240.73286ms","start":"2026-08-28T13:44:49.596246Z","end":"2026-08-28T13:44:49.836979Z","steps":["trace[343572243] 'process raft request' (duration: 240.248489ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:44:49.8371Z","caller":"traceutil/trace.go:171","msg":"trace[1657495531] transaction","detail":"{read_only:false; response_revision:16759; number_of_response:1; }","duration":"235.663059ms","start":"2026-08-28T13:44:49.601393Z","end":"2026-08-28T13:44:49.837056Z","steps":["trace[1657495531] 'process raft request' (duration: 235.135446ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:44:49.837131Z","caller":"traceutil/trace.go:171","msg":"trace[450039895] transaction","detail":"{read_only:false; response_revision:16757; number_of_response:1; }","duration":"241.580592ms","start":"2026-08-28T13:44:49.595523Z","end":"2026-08-28T13:44:49.837103Z","steps":["trace[450039895] 'process raft request' (duration: 240.918145ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:44:49.836968Z","caller":"traceutil/trace.go:171","msg":"trace[1953489231] transaction","detail":"{read_only:false; response_revision:16756; number_of_response:1; }","duration":"242.037433ms","start":"2026-08-28T13:44:49.594889Z","end":"2026-08-28T13:44:49.836926Z","steps":["trace[1953489231] 'process raft request' (duration: 197.134269ms)","trace[1953489231] 'compare' (duration: 44.016591ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:45:14.600346Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14355} {"level":"info","ts":"2026-08-28T13:45:14.686303Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14355,"took":"82.516676ms","hash":249853160,"current-db-size-bytes":43778048,"current-db-size":"44 MB","current-db-size-in-use-bytes":30650368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-28T13:45:14.687513Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":249853160,"revision":14355,"compact-revision":12282} {"level":"info","ts":"2026-08-28T13:50:14.607529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16885} {"level":"info","ts":"2026-08-28T13:50:14.721064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16885,"took":"108.126938ms","hash":961110018,"current-db-size-bytes":45060096,"current-db-size":"45 MB","current-db-size-in-use-bytes":31133696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-28T13:50:14.721146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":961110018,"revision":16885,"compact-revision":14355} {"level":"warn","ts":"2026-08-28T13:50:34.028497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.767708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"info","ts":"2026-08-28T13:50:34.028616Z","caller":"traceutil/trace.go:171","msg":"trace[624471919] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:19200; }","duration":"199.943382ms","start":"2026-08-28T13:50:33.828644Z","end":"2026-08-28T13:50:34.028588Z","steps":["trace[624471919] 'range keys from in-memory index tree' (duration: 199.558273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:34.028682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.471759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-28T13:50:34.028806Z","caller":"traceutil/trace.go:171","msg":"trace[1260658348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19200; }","duration":"180.616363ms","start":"2026-08-28T13:50:33.848152Z","end":"2026-08-28T13:50:34.028768Z","steps":["trace[1260658348] 'range keys from in-memory index tree' (duration: 180.041869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:34.02914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.761896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-28T13:50:34.029258Z","caller":"traceutil/trace.go:171","msg":"trace[2040743902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19200; }","duration":"185.92641ms","start":"2026-08-28T13:50:33.843304Z","end":"2026-08-28T13:50:34.02923Z","steps":["trace[2040743902] 'range keys from in-memory index tree' (duration: 184.929554ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:50:34.080984Z","caller":"traceutil/trace.go:171","msg":"trace[729415313] linearizableReadLoop","detail":"{readStateIndex:19863; appliedIndex:19862; }","duration":"167.431091ms","start":"2026-08-28T13:50:33.913531Z","end":"2026-08-28T13:50:34.080962Z","steps":["trace[729415313] 'read index received' (duration: 167.252067ms)","trace[729415313] 'applied index is now lower than readState.Index' (duration: 177.364µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:50:34.081239Z","caller":"traceutil/trace.go:171","msg":"trace[1954600168] transaction","detail":"{read_only:false; response_revision:19201; number_of_response:1; }","duration":"176.952048ms","start":"2026-08-28T13:50:33.904245Z","end":"2026-08-28T13:50:34.081197Z","steps":["trace[1954600168] 'process raft request' (duration: 176.522406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:34.081491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.939335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-28T13:50:34.081602Z","caller":"traceutil/trace.go:171","msg":"trace[2002172108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19201; }","duration":"168.1355ms","start":"2026-08-28T13:50:33.913445Z","end":"2026-08-28T13:50:34.08158Z","steps":["trace[2002172108] 'agreement among raft nodes before linearized reading' (duration: 167.781431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:34.094672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.798078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-28T13:50:34.094739Z","caller":"traceutil/trace.go:171","msg":"trace[2015370813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19203; }","duration":"122.981141ms","start":"2026-08-28T13:50:33.971741Z","end":"2026-08-28T13:50:34.094722Z","steps":["trace[2015370813] 'agreement among raft nodes before linearized reading' (duration: 122.650493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:34.095161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.193687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T13:50:34.095215Z","caller":"traceutil/trace.go:171","msg":"trace[517821460] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:19203; }","duration":"118.336491ms","start":"2026-08-28T13:50:33.976864Z","end":"2026-08-28T13:50:34.095201Z","steps":["trace[517821460] 'agreement among raft nodes before linearized reading' (duration: 118.251078ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:50:43.329732Z","caller":"traceutil/trace.go:171","msg":"trace[1715827540] transaction","detail":"{read_only:false; response_revision:19253; number_of_response:1; }","duration":"209.707786ms","start":"2026-08-28T13:50:43.119986Z","end":"2026-08-28T13:50:43.329694Z","steps":["trace[1715827540] 'process raft request' (duration: 209.512191ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:50:43.464444Z","caller":"traceutil/trace.go:171","msg":"trace[34534762] linearizableReadLoop","detail":"{readStateIndex:19917; appliedIndex:19915; }","duration":"248.12653ms","start":"2026-08-28T13:50:43.216283Z","end":"2026-08-28T13:50:43.46441Z","steps":["trace[34534762] 'read index received' (duration: 113.20318ms)","trace[34534762] 'applied index is now lower than readState.Index' (duration: 134.92235ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:50:43.464578Z","caller":"traceutil/trace.go:171","msg":"trace[2130718160] transaction","detail":"{read_only:false; response_revision:19254; number_of_response:1; }","duration":"293.764159ms","start":"2026-08-28T13:50:43.170778Z","end":"2026-08-28T13:50:43.464542Z","steps":["trace[2130718160] 'process raft request' (duration: 259.709439ms)","trace[2130718160] 'compare' (duration: 33.794304ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:50:43.464761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.458199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-08-28T13:50:43.464811Z","caller":"traceutil/trace.go:171","msg":"trace[453758299] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19254; }","duration":"248.568672ms","start":"2026-08-28T13:50:43.216229Z","end":"2026-08-28T13:50:43.464798Z","steps":["trace[453758299] 'agreement among raft nodes before linearized reading' (duration: 248.363975ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:50:43.899796Z","caller":"traceutil/trace.go:171","msg":"trace[1846925220] linearizableReadLoop","detail":"{readStateIndex:19918; appliedIndex:19917; }","duration":"250.649005ms","start":"2026-08-28T13:50:43.649113Z","end":"2026-08-28T13:50:43.899762Z","steps":["trace[1846925220] 'read index received' (duration: 191.783941ms)","trace[1846925220] 'applied index is now lower than readState.Index' (duration: 58.863144ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:50:43.900258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.109597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-08-28T13:50:43.900392Z","caller":"traceutil/trace.go:171","msg":"trace[471879386] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19254; }","duration":"251.25319ms","start":"2026-08-28T13:50:43.649105Z","end":"2026-08-28T13:50:43.900358Z","steps":["trace[471879386] 'agreement among raft nodes before linearized reading' (duration: 250.85338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:43.900433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.933447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"warn","ts":"2026-08-28T13:50:43.900466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.629617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-28T13:50:43.900523Z","caller":"traceutil/trace.go:171","msg":"trace[2127032892] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:19254; }","duration":"136.07633ms","start":"2026-08-28T13:50:43.764423Z","end":"2026-08-28T13:50:43.900499Z","steps":["trace[2127032892] 'agreement among raft nodes before linearized reading' (duration: 135.819993ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:50:43.900579Z","caller":"traceutil/trace.go:171","msg":"trace[1874474940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19254; }","duration":"121.808941ms","start":"2026-08-28T13:50:43.778739Z","end":"2026-08-28T13:50:43.900548Z","steps":["trace[1874474940] 'agreement among raft nodes before linearized reading' (duration: 121.479523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:43.901129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.884246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-xxpxv\" ","response":"range_response_count:1 size:20736"} {"level":"info","ts":"2026-08-28T13:50:43.901191Z","caller":"traceutil/trace.go:171","msg":"trace[1218767552] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-xxpxv; range_end:; response_count:1; response_revision:19254; }","duration":"152.991348ms","start":"2026-08-28T13:50:43.748181Z","end":"2026-08-28T13:50:43.901172Z","steps":["trace[1218767552] 'agreement among raft nodes before linearized reading' (duration: 151.957002ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:50:44.523283Z","caller":"traceutil/trace.go:171","msg":"trace[1491161666] transaction","detail":"{read_only:false; response_revision:19255; number_of_response:1; }","duration":"617.492665ms","start":"2026-08-28T13:50:43.90575Z","end":"2026-08-28T13:50:44.523242Z","steps":["trace[1491161666] 'process raft request' (duration: 617.098476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:44.523489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:50:43.905723Z","time spent":"617.661971ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T13:50:44.629176Z","caller":"traceutil/trace.go:171","msg":"trace[1058198309] transaction","detail":"{read_only:false; response_revision:19256; number_of_response:1; }","duration":"702.691281ms","start":"2026-08-28T13:50:43.926447Z","end":"2026-08-28T13:50:44.629139Z","steps":["trace[1058198309] 'process raft request' (duration: 685.572547ms)","trace[1058198309] 'compare' (duration: 16.693392ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:50:44.629355Z","caller":"traceutil/trace.go:171","msg":"trace[1818883129] linearizableReadLoop","detail":"{readStateIndex:19920; appliedIndex:19919; }","duration":"560.556793ms","start":"2026-08-28T13:50:44.068772Z","end":"2026-08-28T13:50:44.629329Z","steps":["trace[1818883129] 'read index received' (duration: 455.519904ms)","trace[1818883129] 'applied index is now lower than readState.Index' (duration: 105.034958ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T13:50:44.629436Z","caller":"traceutil/trace.go:171","msg":"trace[785493262] transaction","detail":"{read_only:false; response_revision:19257; number_of_response:1; }","duration":"128.221448ms","start":"2026-08-28T13:50:44.50119Z","end":"2026-08-28T13:50:44.629411Z","steps":["trace[785493262] 'process raft request' (duration: 127.867159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:44.62939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:50:43.92641Z","time spent":"702.864465ms","remote":"127.0.0.1:59648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20853,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T13:50:44.629689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.924172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"info","ts":"2026-08-28T13:50:44.629747Z","caller":"traceutil/trace.go:171","msg":"trace[1835641278] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:19257; }","duration":"561.024185ms","start":"2026-08-28T13:50:44.068706Z","end":"2026-08-28T13:50:44.62973Z","steps":["trace[1835641278] 'agreement among raft nodes before linearized reading' (duration: 560.777509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:44.629779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:50:44.068682Z","time spent":"561.090687ms","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":7405,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"warn","ts":"2026-08-28T13:50:44.645405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.617311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-28T13:50:44.645512Z","caller":"traceutil/trace.go:171","msg":"trace[796533669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19258; }","duration":"493.800916ms","start":"2026-08-28T13:50:44.151687Z","end":"2026-08-28T13:50:44.645488Z","steps":["trace[796533669] 'agreement among raft nodes before linearized reading' (duration: 493.520349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:44.645558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:50:44.151656Z","time spent":"493.892309ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-28T13:50:44.646119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.455241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T13:50:44.646182Z","caller":"traceutil/trace.go:171","msg":"trace[629324035] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:19258; }","duration":"446.580714ms","start":"2026-08-28T13:50:44.199583Z","end":"2026-08-28T13:50:44.646163Z","steps":["trace[629324035] 'agreement among raft nodes before linearized reading' (duration: 446.462091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:44.646216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:50:44.199556Z","time spent":"446.649905ms","remote":"127.0.0.1:50114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-08-28T13:50:44.646296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.2079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-28T13:50:44.646406Z","caller":"traceutil/trace.go:171","msg":"trace[1151716169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19258; }","duration":"486.392694ms","start":"2026-08-28T13:50:44.159985Z","end":"2026-08-28T13:50:44.646378Z","steps":["trace[1151716169] 'agreement among raft nodes before linearized reading' (duration: 486.102687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:44.646469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:50:44.159929Z","time spent":"486.525867ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-28T13:50:44.646536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.961945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9hfwp\" ","response":"range_response_count:1 size:22091"} {"level":"info","ts":"2026-08-28T13:50:44.646579Z","caller":"traceutil/trace.go:171","msg":"trace[724106165] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9hfwp; range_end:; response_count:1; response_revision:19258; }","duration":"265.046847ms","start":"2026-08-28T13:50:44.38152Z","end":"2026-08-28T13:50:44.646567Z","steps":["trace[724106165] 'agreement among raft nodes before linearized reading' (duration: 264.888793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:44.646834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.917889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-28T13:50:44.646891Z","caller":"traceutil/trace.go:171","msg":"trace[213411454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19258; }","duration":"418.990501ms","start":"2026-08-28T13:50:44.227886Z","end":"2026-08-28T13:50:44.646877Z","steps":["trace[213411454] 'agreement among raft nodes before linearized reading' (duration: 418.802166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:44.646932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:50:44.227875Z","time spent":"419.045762ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-28T13:50:44.647073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.472335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-28T13:50:44.647138Z","caller":"traceutil/trace.go:171","msg":"trace[1699814474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19258; }","duration":"479.600849ms","start":"2026-08-28T13:50:44.167523Z","end":"2026-08-28T13:50:44.647124Z","steps":["trace[1699814474] 'agreement among raft nodes before linearized reading' (duration: 479.409034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:44.64715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.972301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-28T13:50:44.647172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:50:44.167496Z","time spent":"479.66705ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-28T13:50:44.647181Z","caller":"traceutil/trace.go:171","msg":"trace[1876979858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19258; }","duration":"375.024313ms","start":"2026-08-28T13:50:44.272146Z","end":"2026-08-28T13:50:44.64717Z","steps":["trace[1876979858] 'agreement among raft nodes before linearized reading' (duration: 374.970592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:50:44.647209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T13:50:44.272134Z","time spent":"375.068974ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-28T13:50:52.849616Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":20004,"local-member-snapshot-index":10003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T13:50:52.860211Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20004} {"level":"info","ts":"2026-08-28T13:50:52.86038Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15004} {"level":"info","ts":"2026-08-28T13:55:14.614976Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19071} {"level":"info","ts":"2026-08-28T13:55:14.730803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19071,"took":"111.23571ms","hash":2332747804,"current-db-size-bytes":47169536,"current-db-size":"47 MB","current-db-size-in-use-bytes":33673216,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-28T13:55:14.730891Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2332747804,"revision":19071,"compact-revision":16885} {"level":"warn","ts":"2026-08-28T13:58:26.656135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.265015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T13:58:26.65623Z","caller":"traceutil/trace.go:171","msg":"trace[499983355] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:22641; }","duration":"134.379448ms","start":"2026-08-28T13:58:26.52183Z","end":"2026-08-28T13:58:26.65621Z","steps":["trace[499983355] 'count revisions from in-memory index tree' (duration: 134.165253ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:58:33.121153Z","caller":"traceutil/trace.go:171","msg":"trace[227363483] linearizableReadLoop","detail":"{readStateIndex:23449; appliedIndex:23448; }","duration":"244.917556ms","start":"2026-08-28T13:58:32.876205Z","end":"2026-08-28T13:58:33.121122Z","steps":["trace[227363483] 'read index received' (duration: 152.277592ms)","trace[227363483] 'applied index is now lower than readState.Index' (duration: 92.638724ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:58:33.121374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.157873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-28T13:58:33.121404Z","caller":"traceutil/trace.go:171","msg":"trace[671886553] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:22673; }","duration":"245.254965ms","start":"2026-08-28T13:58:32.876139Z","end":"2026-08-28T13:58:33.121394Z","steps":["trace[671886553] 'agreement among raft nodes before linearized reading' (duration: 245.087961ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:58:33.121407Z","caller":"traceutil/trace.go:171","msg":"trace[1404138308] transaction","detail":"{read_only:false; response_revision:22673; number_of_response:1; }","duration":"276.9674ms","start":"2026-08-28T13:58:32.844319Z","end":"2026-08-28T13:58:33.121287Z","steps":["trace[1404138308] 'process raft request' (duration: 184.282064ms)","trace[1404138308] 'compare' (duration: 92.407559ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:58:33.469515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.679568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T13:58:33.469594Z","caller":"traceutil/trace.go:171","msg":"trace[183520174] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:22673; }","duration":"178.813601ms","start":"2026-08-28T13:58:33.290766Z","end":"2026-08-28T13:58:33.46958Z","steps":["trace[183520174] 'count revisions from in-memory index tree' (duration: 178.513913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:58:33.469726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.027139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-28T13:58:33.469886Z","caller":"traceutil/trace.go:171","msg":"trace[873622100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22673; }","duration":"196.251485ms","start":"2026-08-28T13:58:33.273602Z","end":"2026-08-28T13:58:33.469853Z","steps":["trace[873622100] 'range keys from in-memory index tree' (duration: 195.816534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:58:33.469786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.193562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T13:58:33.469967Z","caller":"traceutil/trace.go:171","msg":"trace[995650389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22673; }","duration":"196.409689ms","start":"2026-08-28T13:58:33.273525Z","end":"2026-08-28T13:58:33.469935Z","steps":["trace[995650389] 'range keys from in-memory index tree' (duration: 196.051379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:58:33.470137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.475841ms","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:8"} {"level":"info","ts":"2026-08-28T13:58:33.470464Z","caller":"traceutil/trace.go:171","msg":"trace[386292982] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:22673; }","duration":"152.430237ms","start":"2026-08-28T13:58:33.318015Z","end":"2026-08-28T13:58:33.470446Z","steps":["trace[386292982] 'count revisions from in-memory index tree' (duration: 151.369369ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:58:33.690461Z","caller":"traceutil/trace.go:171","msg":"trace[1716859474] transaction","detail":"{read_only:false; response_revision:22675; number_of_response:1; }","duration":"214.864517ms","start":"2026-08-28T13:58:33.475546Z","end":"2026-08-28T13:58:33.690411Z","steps":["trace[1716859474] 'process raft request' (duration: 214.536358ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:58:33.691229Z","caller":"traceutil/trace.go:171","msg":"trace[547429789] transaction","detail":"{read_only:false; response_revision:22676; number_of_response:1; }","duration":"203.482682ms","start":"2026-08-28T13:58:33.48771Z","end":"2026-08-28T13:58:33.691193Z","steps":["trace[547429789] 'process raft request' (duration: 203.228184ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T13:58:33.693481Z","caller":"traceutil/trace.go:171","msg":"trace[2102968900] linearizableReadLoop","detail":"{readStateIndex:23452; appliedIndex:23450; }","duration":"195.864674ms","start":"2026-08-28T13:58:33.497595Z","end":"2026-08-28T13:58:33.693459Z","steps":["trace[2102968900] 'read index received' (duration: 192.574969ms)","trace[2102968900] 'applied index is now lower than readState.Index' (duration: 3.288025ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T13:58:33.693774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.162731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-28T13:58:33.693813Z","caller":"traceutil/trace.go:171","msg":"trace[1517658999] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22676; }","duration":"196.222773ms","start":"2026-08-28T13:58:33.49758Z","end":"2026-08-28T13:58:33.693803Z","steps":["trace[1517658999] 'agreement among raft nodes before linearized reading' (duration: 196.006537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T13:58:33.693944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.346329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-28T13:58:33.694045Z","caller":"traceutil/trace.go:171","msg":"trace[1291817224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22676; }","duration":"166.524543ms","start":"2026-08-28T13:58:33.527496Z","end":"2026-08-28T13:58:33.694021Z","steps":["trace[1291817224] 'agreement among raft nodes before linearized reading' (duration: 166.164713ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T14:00:14.623161Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21366} {"level":"info","ts":"2026-08-28T14:00:14.749949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21366,"took":"122.65732ms","hash":353564252,"current-db-size-bytes":47169536,"current-db-size":"47 MB","current-db-size-in-use-bytes":28975104,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-28T14:00:14.750024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":353564252,"revision":21366,"compact-revision":19071} {"level":"info","ts":"2026-08-28T14:02:54.971241Z","caller":"traceutil/trace.go:171","msg":"trace[1940097340] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"163.062976ms","start":"2026-08-28T14:02:54.808148Z","end":"2026-08-28T14:02:54.971211Z","steps":["trace[1940097340] 'process raft request' (duration: 162.858711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T14:02:55.165021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.653134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T14:02:55.165117Z","caller":"traceutil/trace.go:171","msg":"trace[285623212] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25325; }","duration":"160.831119ms","start":"2026-08-28T14:02:55.004261Z","end":"2026-08-28T14:02:55.165092Z","steps":["trace[285623212] 'count revisions from in-memory index tree' (duration: 160.570652ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T14:02:55.387629Z","caller":"traceutil/trace.go:171","msg":"trace[1602274148] linearizableReadLoop","detail":"{readStateIndex:26184; appliedIndex:26183; }","duration":"197.854039ms","start":"2026-08-28T14:02:55.189749Z","end":"2026-08-28T14:02:55.387603Z","steps":["trace[1602274148] 'read index received' (duration: 140.877663ms)","trace[1602274148] 'applied index is now lower than readState.Index' (duration: 56.975406ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T14:02:55.387769Z","caller":"traceutil/trace.go:171","msg":"trace[1949519069] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"209.305105ms","start":"2026-08-28T14:02:55.17845Z","end":"2026-08-28T14:02:55.387755Z","steps":["trace[1949519069] 'process raft request' (duration: 152.245956ms)","trace[1949519069] 'compare' (duration: 56.727571ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T14:02:55.388112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.340322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-28T14:02:55.38826Z","caller":"traceutil/trace.go:171","msg":"trace[1238298757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25328; }","duration":"198.493036ms","start":"2026-08-28T14:02:55.189698Z","end":"2026-08-28T14:02:55.388191Z","steps":["trace[1238298757] 'agreement among raft nodes before linearized reading' (duration: 198.127726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T14:02:55.388731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.550435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-28T14:02:55.388778Z","caller":"traceutil/trace.go:171","msg":"trace[958745121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25328; }","duration":"159.615336ms","start":"2026-08-28T14:02:55.229147Z","end":"2026-08-28T14:02:55.388762Z","steps":["trace[958745121] 'agreement among raft nodes before linearized reading' (duration: 159.507764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T14:02:55.388939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.167451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-28T14:02:55.389086Z","caller":"traceutil/trace.go:171","msg":"trace[1290935887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25328; }","duration":"192.357867ms","start":"2026-08-28T14:02:55.196701Z","end":"2026-08-28T14:02:55.389059Z","steps":["trace[1290935887] 'agreement among raft nodes before linearized reading' (duration: 191.968576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T14:02:55.38914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.078195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-28T14:02:55.38925Z","caller":"traceutil/trace.go:171","msg":"trace[1057174657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25328; }","duration":"166.26398ms","start":"2026-08-28T14:02:55.222957Z","end":"2026-08-28T14:02:55.389221Z","steps":["trace[1057174657] 'agreement among raft nodes before linearized reading' (duration: 165.926431ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T14:04:33.081839Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a58.wal"} {"level":"info","ts":"2026-08-28T14:05:14.632964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23593} {"level":"info","ts":"2026-08-28T14:05:14.756451Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23593,"took":"118.607334ms","hash":3355673466,"current-db-size-bytes":51576832,"current-db-size":"52 MB","current-db-size-in-use-bytes":44331008,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-28T14:05:14.756541Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3355673466,"revision":23593,"compact-revision":21366} {"level":"info","ts":"2026-08-28T14:10:14.642433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26717} {"level":"info","ts":"2026-08-28T14:10:14.788522Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26717,"took":"137.062149ms","hash":2310209267,"current-db-size-bytes":51576832,"current-db-size":"52 MB","current-db-size-in-use-bytes":31023104,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-28T14:10:14.78858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2310209267,"revision":26717,"compact-revision":23593} {"level":"info","ts":"2026-08-28T14:10:27.964782Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":30005,"local-member-snapshot-index":20004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T14:10:27.970653Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30005} {"level":"info","ts":"2026-08-28T14:10:27.97074Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25005}