{"level":"warn","ts":"2026-08-27T19:14:45.754306Z","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-27T19:14:45.754403Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-27T19:14:45.754484Z","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-27T19:14:45.754502Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-08-27T19:14:45.754525Z","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-27T19:14:45.755303Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-08-27T19:14:45.755409Z","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.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-27T19:14:45.761294Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.661967ms"} {"level":"info","ts":"2026-08-27T19:14:45.77409Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-08-27T19:14:45.774206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-08-27T19:14:45.774261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-08-27T19:14:45.77428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-27T19:14:45.774291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-08-27T19:14:45.774341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-08-27T19:14:45.784788Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-27T19:14:45.786849Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-27T19:14:45.788754Z","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-27T19:14:45.792149Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-27T19:14:45.792368Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-27T19:14:45.792458Z","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-27T19:14:45.792538Z","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-27T19:14:45.792552Z","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-27T19:14:45.794495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-08-27T19:14:45.795134Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-08-27T19:14:45.797182Z","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-27T19:14:45.797328Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-08-27T19:14:45.797414Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-08-27T19:14:45.797606Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-27T19:14:45.797606Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-27T19:14:46.47484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-08-27T19:14:46.474909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-27T19:14:46.474948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-08-27T19:14:46.474967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-08-27T19:14:46.474977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-08-27T19:14:46.474998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-08-27T19:14:46.47501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-08-27T19:14:46.476422Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-27T19:14:46.477551Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-08-27T19:14:46.477563Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-27T19:14:46.477607Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-27T19:14:46.477806Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-27T19:14:46.477883Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-27T19:14:46.478446Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-27T19:14:46.478592Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-27T19:14:46.478633Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-27T19:14:46.481318Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-27T19:14:46.481513Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"warn","ts":"2026-08-27T19:15:05.74439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.763342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:15:05.744561Z","caller":"traceutil/trace.go:171","msg":"trace[936168569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"110.956966ms","start":"2026-08-27T19:15:05.63357Z","end":"2026-08-27T19:15:05.744527Z","steps":["trace[936168569] 'range keys from in-memory index tree' (duration: 110.65966ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:15:28.887743Z","caller":"traceutil/trace.go:171","msg":"trace[561411439] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"251.681792ms","start":"2026-08-27T19:15:28.636025Z","end":"2026-08-27T19:15:28.887707Z","steps":["trace[561411439] 'process raft request' (duration: 250.786901ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:15:28.893465Z","caller":"traceutil/trace.go:171","msg":"trace[1174637491] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"213.330174ms","start":"2026-08-27T19:15:28.680068Z","end":"2026-08-27T19:15:28.893398Z","steps":["trace[1174637491] 'read index received' (duration: 206.409377ms)","trace[1174637491] 'applied index is now lower than readState.Index' (duration: 6.919147ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:15:28.893562Z","caller":"traceutil/trace.go:171","msg":"trace[1086422364] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"220.103978ms","start":"2026-08-27T19:15:28.67342Z","end":"2026-08-27T19:15:28.893524Z","steps":["trace[1086422364] 'process raft request' (duration: 219.797871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:15:28.89379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.698329ms","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-27T19:15:28.89384Z","caller":"traceutil/trace.go:171","msg":"trace[813412808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"174.822772ms","start":"2026-08-27T19:15:28.719003Z","end":"2026-08-27T19:15:28.893826Z","steps":["trace[813412808] 'agreement among raft nodes before linearized reading' (duration: 174.606857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:15:28.893979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.767336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q6ksc\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-27T19:15:28.894083Z","caller":"traceutil/trace.go:171","msg":"trace[866354153] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q6ksc; range_end:; response_count:1; response_revision:586; }","duration":"214.031292ms","start":"2026-08-27T19:15:28.680024Z","end":"2026-08-27T19:15:28.894056Z","steps":["trace[866354153] 'agreement among raft nodes before linearized reading' (duration: 213.561281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:15:29.466082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.78517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-27T19:15:29.466214Z","caller":"traceutil/trace.go:171","msg":"trace[1677819975] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"553.309637ms","start":"2026-08-27T19:15:28.912884Z","end":"2026-08-27T19:15:29.466194Z","steps":["trace[1677819975] 'process raft request' (duration: 387.375829ms)","trace[1677819975] 'compare' (duration: 164.641637ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:15:29.466277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:15:28.912847Z","time spent":"553.398199ms","remote":"127.0.0.1:59050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-27T19:15:29.539549Z","caller":"traceutil/trace.go:171","msg":"trace[1488871921] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"578.895945ms","start":"2026-08-27T19:15:28.960614Z","end":"2026-08-27T19:15:29.53951Z","steps":["trace[1488871921] 'process raft request' (duration: 578.70076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:15:29.539749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:15:28.960543Z","time spent":"579.112961ms","remote":"127.0.0.1:59360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-27T19:15:46.049408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.750266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-97qgd\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-27T19:15:46.049532Z","caller":"traceutil/trace.go:171","msg":"trace[1176600529] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-97qgd; range_end:; response_count:1; response_revision:749; }","duration":"258.94278ms","start":"2026-08-27T19:15:45.790568Z","end":"2026-08-27T19:15:46.049511Z","steps":["trace[1176600529] 'range keys from in-memory index tree' (duration: 258.510361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:15:46.049606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.287002ms","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-27T19:15:46.049745Z","caller":"traceutil/trace.go:171","msg":"trace[542640475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:749; }","duration":"297.442015ms","start":"2026-08-27T19:15:45.752265Z","end":"2026-08-27T19:15:46.049707Z","steps":["trace[542640475] 'range keys from in-memory index tree' (duration: 296.963233ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:17:08.433081Z","caller":"traceutil/trace.go:171","msg":"trace[1165139897] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"227.850001ms","start":"2026-08-27T19:17:08.205206Z","end":"2026-08-27T19:17:08.433056Z","steps":["trace[1165139897] 'read index received' (duration: 227.596806ms)","trace[1165139897] 'applied index is now lower than readState.Index' (duration: 252.105µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:17:08.433142Z","caller":"traceutil/trace.go:171","msg":"trace[470436722] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"234.055961ms","start":"2026-08-27T19:17:08.199044Z","end":"2026-08-27T19:17:08.4331Z","steps":["trace[470436722] 'process raft request' (duration: 233.861857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:17:08.433217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.998825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-27T19:17:08.433256Z","caller":"traceutil/trace.go:171","msg":"trace[573151250] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1855; }","duration":"228.104487ms","start":"2026-08-27T19:17:08.205137Z","end":"2026-08-27T19:17:08.433242Z","steps":["trace[573151250] 'agreement among raft nodes before linearized reading' (duration: 228.020805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:17:08.714912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.514569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-27T19:17:08.714925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.584326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e76243ff-1ac2-4cb9-ab9b-bf6d2cfc176f\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-08-27T19:17:08.714978Z","caller":"traceutil/trace.go:171","msg":"trace[1633166477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1855; }","duration":"115.594971ms","start":"2026-08-27T19:17:08.599367Z","end":"2026-08-27T19:17:08.714962Z","steps":["trace[1633166477] 'range keys from in-memory index tree' (duration: 115.299795ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:17:08.715Z","caller":"traceutil/trace.go:171","msg":"trace[565825845] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e76243ff-1ac2-4cb9-ab9b-bf6d2cfc176f; range_end:; response_count:1; response_revision:1855; }","duration":"200.719778ms","start":"2026-08-27T19:17:08.514264Z","end":"2026-08-27T19:17:08.714984Z","steps":["trace[565825845] 'range keys from in-memory index tree' (duration: 200.511944ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:17:21.024621Z","caller":"traceutil/trace.go:171","msg":"trace[1670980172] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"146.695586ms","start":"2026-08-27T19:17:20.877889Z","end":"2026-08-27T19:17:21.024584Z","steps":["trace[1670980172] 'process raft request' (duration: 129.392858ms)","trace[1670980172] 'compare' (duration: 16.966542ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:17:59.336734Z","caller":"traceutil/trace.go:171","msg":"trace[1067040347] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2262; }","duration":"190.052042ms","start":"2026-08-27T19:17:59.146656Z","end":"2026-08-27T19:17:59.336708Z","steps":["trace[1067040347] 'read index received' (duration: 189.922499ms)","trace[1067040347] 'applied index is now lower than readState.Index' (duration: 128.053µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:17:59.336847Z","caller":"traceutil/trace.go:171","msg":"trace[1552897904] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"292.862291ms","start":"2026-08-27T19:17:59.043958Z","end":"2026-08-27T19:17:59.336821Z","steps":["trace[1552897904] 'process raft request' (duration: 292.606856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:17:59.33692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.240706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-27T19:17:59.336959Z","caller":"traceutil/trace.go:171","msg":"trace[1139642402] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2180; }","duration":"190.324757ms","start":"2026-08-27T19:17:59.146624Z","end":"2026-08-27T19:17:59.336949Z","steps":["trace[1139642402] 'agreement among raft nodes before linearized reading' (duration: 190.234555ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:17:59.362156Z","caller":"traceutil/trace.go:171","msg":"trace[1368624316] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"213.252666ms","start":"2026-08-27T19:17:59.148873Z","end":"2026-08-27T19:17:59.362126Z","steps":["trace[1368624316] 'process raft request' (duration: 213.023011ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:17:59.580457Z","caller":"traceutil/trace.go:171","msg":"trace[558894099] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"212.920309ms","start":"2026-08-27T19:17:59.367503Z","end":"2026-08-27T19:17:59.580424Z","steps":["trace[558894099] 'process raft request' (duration: 149.611969ms)","trace[558894099] 'compare' (duration: 63.198317ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:19:30.086628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.910437ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-08-27T19:19:30.300509Z","caller":"traceutil/trace.go:171","msg":"trace[702036105] linearizableReadLoop","detail":"{readStateIndex:2978; appliedIndex:2977; }","duration":"216.19861ms","start":"2026-08-27T19:19:30.084282Z","end":"2026-08-27T19:19:30.30048Z","steps":["trace[702036105] 'read index received' (duration: 129.472552ms)","trace[702036105] 'applied index is now lower than readState.Index' (duration: 86.725048ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:19:30.300801Z","caller":"traceutil/trace.go:171","msg":"trace[75793978] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"229.762953ms","start":"2026-08-27T19:19:30.071025Z","end":"2026-08-27T19:19:30.300788Z","steps":["trace[75793978] 'process raft request' (duration: 142.697006ms)","trace[75793978] 'compare' (duration: 86.652326ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:19:30.301065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.773153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-27T19:19:30.301103Z","caller":"traceutil/trace.go:171","msg":"trace[677502262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2867; }","duration":"216.868325ms","start":"2026-08-27T19:19:30.084225Z","end":"2026-08-27T19:19:30.301093Z","steps":["trace[677502262] 'agreement among raft nodes before linearized reading' (duration: 216.736492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:19:30.301602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.945006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-27T19:19:30.301647Z","caller":"traceutil/trace.go:171","msg":"trace[508155255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2869; }","duration":"190.023668ms","start":"2026-08-27T19:19:30.111614Z","end":"2026-08-27T19:19:30.301638Z","steps":["trace[508155255] 'agreement among raft nodes before linearized reading' (duration: 189.911325ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:19:30.301782Z","caller":"traceutil/trace.go:171","msg":"trace[269415154] transaction","detail":"{read_only:false; response_revision:2868; number_of_response:1; }","duration":"212.983356ms","start":"2026-08-27T19:19:30.08879Z","end":"2026-08-27T19:19:30.301773Z","steps":["trace[269415154] 'process raft request' (duration: 212.636017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:19:30.302018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.485347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-27T19:19:30.302005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.042786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-27T19:19:30.302169Z","caller":"traceutil/trace.go:171","msg":"trace[1803776544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2869; }","duration":"114.555479ms","start":"2026-08-27T19:19:30.18749Z","end":"2026-08-27T19:19:30.302046Z","steps":["trace[1803776544] 'agreement among raft nodes before linearized reading' (duration: 114.462837ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:19:30.302314Z","caller":"traceutil/trace.go:171","msg":"trace[1175952730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2869; }","duration":"114.151619ms","start":"2026-08-27T19:19:30.187912Z","end":"2026-08-27T19:19:30.302063Z","steps":["trace[1175952730] 'agreement among raft nodes before linearized reading' (duration: 113.871403ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:19:30.302339Z","caller":"traceutil/trace.go:171","msg":"trace[1406438014] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"121.5394ms","start":"2026-08-27T19:19:30.180536Z","end":"2026-08-27T19:19:30.302076Z","steps":["trace[1406438014] 'process raft request' (duration: 120.950666ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:15.546443Z","caller":"traceutil/trace.go:171","msg":"trace[99927957] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"175.759371ms","start":"2026-08-27T19:24:15.370654Z","end":"2026-08-27T19:24:15.546413Z","steps":["trace[99927957] 'process raft request' (duration: 175.597688ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:15.605209Z","caller":"traceutil/trace.go:171","msg":"trace[1809531013] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"214.135555ms","start":"2026-08-27T19:24:15.391051Z","end":"2026-08-27T19:24:15.605187Z","steps":["trace[1809531013] 'process raft request' (duration: 214.079944ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:15.605394Z","caller":"traceutil/trace.go:171","msg":"trace[301615227] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"216.227545ms","start":"2026-08-27T19:24:15.389153Z","end":"2026-08-27T19:24:15.605381Z","steps":["trace[301615227] 'process raft request' (duration: 214.427843ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:17.723966Z","caller":"traceutil/trace.go:171","msg":"trace[539911597] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"112.07932ms","start":"2026-08-27T19:24:17.611851Z","end":"2026-08-27T19:24:17.723931Z","steps":["trace[539911597] 'process raft request' (duration: 73.86002ms)","trace[539911597] 'compare' (duration: 38.036166ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:24:32.024826Z","caller":"traceutil/trace.go:171","msg":"trace[930794042] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"291.839857ms","start":"2026-08-27T19:24:31.732952Z","end":"2026-08-27T19:24:32.024792Z","steps":["trace[930794042] 'process raft request' (duration: 270.158436ms)","trace[930794042] 'compare' (duration: 21.198859ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:24:32.026268Z","caller":"traceutil/trace.go:171","msg":"trace[1609639314] linearizableReadLoop","detail":"{readStateIndex:5771; appliedIndex:5769; }","duration":"136.473676ms","start":"2026-08-27T19:24:31.889767Z","end":"2026-08-27T19:24:32.02624Z","steps":["trace[1609639314] 'read index received' (duration: 112.784727ms)","trace[1609639314] 'applied index is now lower than readState.Index' (duration: 23.687919ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:24:32.026735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.947327ms","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-27T19:24:32.026783Z","caller":"traceutil/trace.go:171","msg":"trace[793630475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5561; }","duration":"137.058109ms","start":"2026-08-27T19:24:31.889713Z","end":"2026-08-27T19:24:32.026771Z","steps":["trace[793630475] 'agreement among raft nodes before linearized reading' (duration: 136.884455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:32.026768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.460688ms","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-27T19:24:32.026861Z","caller":"traceutil/trace.go:171","msg":"trace[895503168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5561; }","duration":"120.583451ms","start":"2026-08-27T19:24:31.906256Z","end":"2026-08-27T19:24:32.026839Z","steps":["trace[895503168] 'agreement among raft nodes before linearized reading' (duration: 120.354586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:32.026829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.442894ms","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-27T19:24:32.026886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.52378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-27T19:24:32.026951Z","caller":"traceutil/trace.go:171","msg":"trace[1971150091] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"248.866103ms","start":"2026-08-27T19:24:31.778074Z","end":"2026-08-27T19:24:32.02694Z","steps":["trace[1971150091] 'process raft request' (duration: 248.033813ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:32.026964Z","caller":"traceutil/trace.go:171","msg":"trace[758942054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5561; }","duration":"123.635543ms","start":"2026-08-27T19:24:31.903311Z","end":"2026-08-27T19:24:32.026947Z","steps":["trace[758942054] 'agreement among raft nodes before linearized reading' (duration: 123.382667ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:32.026964Z","caller":"traceutil/trace.go:171","msg":"trace[284766523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5561; }","duration":"130.628418ms","start":"2026-08-27T19:24:31.896306Z","end":"2026-08-27T19:24:32.026934Z","steps":["trace[284766523] 'agreement among raft nodes before linearized reading' (duration: 130.28984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:43.699676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.301589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-27T19:24:43.699781Z","caller":"traceutil/trace.go:171","msg":"trace[425585812] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:5787; }","duration":"166.462292ms","start":"2026-08-27T19:24:43.533298Z","end":"2026-08-27T19:24:43.699761Z","steps":["trace[425585812] 'count revisions from in-memory index tree' (duration: 166.231607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:43.699905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.162442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4360ab4c-8878-459a-9f92-95710f8a56a2\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-27T19:24:43.700053Z","caller":"traceutil/trace.go:171","msg":"trace[925783080] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4360ab4c-8878-459a-9f92-95710f8a56a2; range_end:; response_count:1; response_revision:5787; }","duration":"137.380197ms","start":"2026-08-27T19:24:43.562641Z","end":"2026-08-27T19:24:43.700021Z","steps":["trace[925783080] 'range keys from in-memory index tree' (duration: 136.890575ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:43.928498Z","caller":"traceutil/trace.go:171","msg":"trace[876604775] transaction","detail":"{read_only:false; response_revision:5788; number_of_response:1; }","duration":"207.195862ms","start":"2026-08-27T19:24:43.721254Z","end":"2026-08-27T19:24:43.92845Z","steps":["trace[876604775] 'process raft request' (duration: 206.980617ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:44.173551Z","caller":"traceutil/trace.go:171","msg":"trace[1958728312] linearizableReadLoop","detail":"{readStateIndex:6003; appliedIndex:6002; }","duration":"155.273749ms","start":"2026-08-27T19:24:44.018245Z","end":"2026-08-27T19:24:44.173518Z","steps":["trace[1958728312] 'read index received' (duration: 154.930841ms)","trace[1958728312] 'applied index is now lower than readState.Index' (duration: 341.278µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:24:44.173621Z","caller":"traceutil/trace.go:171","msg":"trace[84497481] transaction","detail":"{read_only:false; response_revision:5789; number_of_response:1; }","duration":"205.798929ms","start":"2026-08-27T19:24:43.967771Z","end":"2026-08-27T19:24:44.17357Z","steps":["trace[84497481] 'process raft request' (duration: 205.515603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:44.17379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.490054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-27T19:24:44.173925Z","caller":"traceutil/trace.go:171","msg":"trace[1127846309] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5789; }","duration":"155.661288ms","start":"2026-08-27T19:24:44.018237Z","end":"2026-08-27T19:24:44.173898Z","steps":["trace[1127846309] 'agreement among raft nodes before linearized reading' (duration: 155.403622ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:44.22779Z","caller":"traceutil/trace.go:171","msg":"trace[424244478] transaction","detail":"{read_only:false; response_revision:5790; number_of_response:1; }","duration":"134.856847ms","start":"2026-08-27T19:24:44.092898Z","end":"2026-08-27T19:24:44.227755Z","steps":["trace[424244478] 'process raft request' (duration: 134.1614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:44.228322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.272736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-27T19:24:44.228415Z","caller":"traceutil/trace.go:171","msg":"trace[1047482661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5791; }","duration":"126.381538ms","start":"2026-08-27T19:24:44.102016Z","end":"2026-08-27T19:24:44.228398Z","steps":["trace[1047482661] 'agreement among raft nodes before linearized reading' (duration: 126.129501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:44.228417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.74063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-27T19:24:44.228462Z","caller":"traceutil/trace.go:171","msg":"trace[1336668028] transaction","detail":"{read_only:false; response_revision:5791; number_of_response:1; }","duration":"132.224006ms","start":"2026-08-27T19:24:44.096157Z","end":"2026-08-27T19:24:44.228381Z","steps":["trace[1336668028] 'process raft request' (duration: 131.12131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:44.228451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.598674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-27T19:24:44.228527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.635087ms","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-27T19:24:44.228603Z","caller":"traceutil/trace.go:171","msg":"trace[304795949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5791; }","duration":"124.78499ms","start":"2026-08-27T19:24:44.103798Z","end":"2026-08-27T19:24:44.228583Z","steps":["trace[304795949] 'agreement among raft nodes before linearized reading' (duration: 124.517364ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:44.228604Z","caller":"traceutil/trace.go:171","msg":"trace[979793424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5791; }","duration":"115.90673ms","start":"2026-08-27T19:24:44.112671Z","end":"2026-08-27T19:24:44.228578Z","steps":["trace[979793424] 'agreement among raft nodes before linearized reading' (duration: 115.311536ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:44.228529Z","caller":"traceutil/trace.go:171","msg":"trace[1953438732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5791; }","duration":"127.894763ms","start":"2026-08-27T19:24:44.100609Z","end":"2026-08-27T19:24:44.228503Z","steps":["trace[1953438732] 'agreement among raft nodes before linearized reading' (duration: 127.573265ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:46.509794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2962} {"level":"info","ts":"2026-08-27T19:24:46.589212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2962,"took":"77.777253ms","hash":1482196274,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":32075776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-27T19:24:46.589299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1482196274,"revision":2962,"compact-revision":-1} {"level":"info","ts":"2026-08-27T19:29:46.517088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5834} {"level":"info","ts":"2026-08-27T19:29:46.613859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5834,"took":"94.415561ms","hash":3322661480,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":26124288,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-27T19:29:46.613966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3322661480,"revision":5834,"compact-revision":2962} {"level":"info","ts":"2026-08-27T19:31:10.495399Z","caller":"traceutil/trace.go:171","msg":"trace[1230293649] linearizableReadLoop","detail":"{readStateIndex:8986; appliedIndex:8985; }","duration":"459.948154ms","start":"2026-08-27T19:31:10.035425Z","end":"2026-08-27T19:31:10.495373Z","steps":["trace[1230293649] 'read index received' (duration: 428.027228ms)","trace[1230293649] 'applied index is now lower than readState.Index' (duration: 31.919316ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:31:10.495536Z","caller":"traceutil/trace.go:171","msg":"trace[836745510] transaction","detail":"{read_only:false; response_revision:8675; number_of_response:1; }","duration":"493.709456ms","start":"2026-08-27T19:31:10.001781Z","end":"2026-08-27T19:31:10.49549Z","steps":["trace[836745510] 'process raft request' (duration: 461.670877ms)","trace[836745510] 'compare' (duration: 31.625559ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:31:10.495634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.1901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-27T19:31:10.495681Z","caller":"traceutil/trace.go:171","msg":"trace[1992361361] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:8675; }","duration":"460.287922ms","start":"2026-08-27T19:31:10.035379Z","end":"2026-08-27T19:31:10.495667Z","steps":["trace[1992361361] 'agreement among raft nodes before linearized reading' (duration: 460.18194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:31:10.495679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:31:10.001752Z","time spent":"493.840459ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:31:10.495712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:31:10.035359Z","time spent":"460.343783ms","remote":"127.0.0.1:59344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":21,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-08-27T19:31:10.495682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.945483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-27T19:31:10.495801Z","caller":"traceutil/trace.go:171","msg":"trace[1679162045] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8675; }","duration":"102.078157ms","start":"2026-08-27T19:31:10.393698Z","end":"2026-08-27T19:31:10.495776Z","steps":["trace[1679162045] 'agreement among raft nodes before linearized reading' (duration: 101.879061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:31:10.495787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.036638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-27T19:31:10.495814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.582597ms","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-27T19:31:10.495815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.118437ms","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-27T19:31:10.495916Z","caller":"traceutil/trace.go:171","msg":"trace[706307199] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8675; }","duration":"131.259675ms","start":"2026-08-27T19:31:10.364624Z","end":"2026-08-27T19:31:10.495884Z","steps":["trace[706307199] 'agreement among raft nodes before linearized reading' (duration: 131.003128ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:10.495932Z","caller":"traceutil/trace.go:171","msg":"trace[677897145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8675; }","duration":"121.72791ms","start":"2026-08-27T19:31:10.374172Z","end":"2026-08-27T19:31:10.4959Z","steps":["trace[677897145] 'agreement among raft nodes before linearized reading' (duration: 121.411421ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:10.495956Z","caller":"traceutil/trace.go:171","msg":"trace[1980057898] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8675; }","duration":"339.309102ms","start":"2026-08-27T19:31:10.156603Z","end":"2026-08-27T19:31:10.495912Z","steps":["trace[1980057898] 'agreement among raft nodes before linearized reading' (duration: 338.976193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:31:10.496009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:31:10.156581Z","time spent":"339.417194ms","remote":"127.0.0.1:58942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":98,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-08-27T19:31:20.810832Z","caller":"traceutil/trace.go:171","msg":"trace[84887714] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"214.776022ms","start":"2026-08-27T19:31:20.596026Z","end":"2026-08-27T19:31:20.810802Z","steps":["trace[84887714] 'process raft request' (duration: 214.592238ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:20.823189Z","caller":"traceutil/trace.go:171","msg":"trace[752100686] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"225.890747ms","start":"2026-08-27T19:31:20.597274Z","end":"2026-08-27T19:31:20.823164Z","steps":["trace[752100686] 'process raft request' (duration: 225.808854ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:20.823253Z","caller":"traceutil/trace.go:171","msg":"trace[915585954] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"226.038551ms","start":"2026-08-27T19:31:20.597179Z","end":"2026-08-27T19:31:20.823217Z","steps":["trace[915585954] 'process raft request' (duration: 225.763213ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:20.823502Z","caller":"traceutil/trace.go:171","msg":"trace[1684747147] linearizableReadLoop","detail":"{readStateIndex:9043; appliedIndex:9039; }","duration":"107.729624ms","start":"2026-08-27T19:31:20.715752Z","end":"2026-08-27T19:31:20.823482Z","steps":["trace[1684747147] 'read index received' (duration: 94.887627ms)","trace[1684747147] 'applied index is now lower than readState.Index' (duration: 12.840537ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:31:20.823522Z","caller":"traceutil/trace.go:171","msg":"trace[934814643] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"226.068901ms","start":"2026-08-27T19:31:20.597412Z","end":"2026-08-27T19:31:20.823481Z","steps":["trace[934814643] 'process raft request' (duration: 225.719173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:31:20.823604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.863578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:31:20.823636Z","caller":"traceutil/trace.go:171","msg":"trace[1889657524] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8732; }","duration":"107.975732ms","start":"2026-08-27T19:31:20.715653Z","end":"2026-08-27T19:31:20.823629Z","steps":["trace[1889657524] 'agreement among raft nodes before linearized reading' (duration: 107.90161ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:21.707186Z","caller":"traceutil/trace.go:171","msg":"trace[1404249094] transaction","detail":"{read_only:false; response_revision:8734; number_of_response:1; }","duration":"101.260205ms","start":"2026-08-27T19:31:21.605892Z","end":"2026-08-27T19:31:21.707152Z","steps":["trace[1404249094] 'process raft request' (duration: 101.04718ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:22.14614Z","caller":"traceutil/trace.go:171","msg":"trace[1622280804] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"130.316052ms","start":"2026-08-27T19:31:22.0158Z","end":"2026-08-27T19:31:22.146116Z","steps":["trace[1622280804] 'process raft request' (duration: 130.159807ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:38.897473Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023b9.wal"} {"level":"info","ts":"2026-08-27T19:31:52.083614Z","caller":"traceutil/trace.go:171","msg":"trace[1988380647] transaction","detail":"{read_only:false; response_revision:8950; number_of_response:1; }","duration":"208.76357ms","start":"2026-08-27T19:31:51.874814Z","end":"2026-08-27T19:31:52.083577Z","steps":["trace[1988380647] 'process raft request' (duration: 208.185906ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:54.330612Z","caller":"traceutil/trace.go:171","msg":"trace[1677332496] transaction","detail":"{read_only:false; response_revision:8962; number_of_response:1; }","duration":"104.055242ms","start":"2026-08-27T19:31:54.226517Z","end":"2026-08-27T19:31:54.330573Z","steps":["trace[1677332496] 'process raft request' (duration: 103.888068ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:32:05.422227Z","caller":"traceutil/trace.go:171","msg":"trace[754250738] linearizableReadLoop","detail":"{readStateIndex:9362; appliedIndex:9360; }","duration":"143.642949ms","start":"2026-08-27T19:32:05.278554Z","end":"2026-08-27T19:32:05.422197Z","steps":["trace[754250738] 'read index received' (duration: 8.186182ms)","trace[754250738] 'applied index is now lower than readState.Index' (duration: 135.455427ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:32:05.422403Z","caller":"traceutil/trace.go:171","msg":"trace[348566295] transaction","detail":"{read_only:false; response_revision:9036; number_of_response:1; }","duration":"149.881906ms","start":"2026-08-27T19:32:05.272506Z","end":"2026-08-27T19:32:05.422388Z","steps":["trace[348566295] 'process raft request' (duration: 149.505918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:32:05.422627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.025605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-55fcdb4678-gb7ht.18cfc058f0c2189d\" ","response":"range_response_count:1 size:1134"} {"level":"warn","ts":"2026-08-27T19:32:05.422663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.084809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:32:05.422735Z","caller":"traceutil/trace.go:171","msg":"trace[518260860] range","detail":"{range_begin:/registry/events/openstack/barbican-api-55fcdb4678-gb7ht.18cfc058f0c2189d; range_end:; response_count:1; response_revision:9036; }","duration":"100.159847ms","start":"2026-08-27T19:32:05.322533Z","end":"2026-08-27T19:32:05.422693Z","steps":["trace[518260860] 'agreement among raft nodes before linearized reading' (duration: 99.936421ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:32:05.422773Z","caller":"traceutil/trace.go:171","msg":"trace[900044109] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9036; }","duration":"144.214792ms","start":"2026-08-27T19:32:05.278534Z","end":"2026-08-27T19:32:05.422749Z","steps":["trace[900044109] 'agreement among raft nodes before linearized reading' (duration: 144.047048ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:33:06.8415Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"14f8f2dfdaccebdb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-27T19:33:06.850048Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-27T19:33:06.850166Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-27T19:33:21.598333Z","caller":"traceutil/trace.go:171","msg":"trace[523606272] transaction","detail":"{read_only:false; response_revision:9808; number_of_response:1; }","duration":"126.597434ms","start":"2026-08-27T19:33:21.471698Z","end":"2026-08-27T19:33:21.598296Z","steps":["trace[523606272] 'process raft request' (duration: 86.439623ms)","trace[523606272] 'compare' (duration: 39.964047ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:33:35.725032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.86568ms","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-27T19:33:35.725125Z","caller":"traceutil/trace.go:171","msg":"trace[954008126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9884; }","duration":"174.028295ms","start":"2026-08-27T19:33:35.551073Z","end":"2026-08-27T19:33:35.725101Z","steps":["trace[954008126] 'range keys from in-memory index tree' (duration: 173.699876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:33:35.725485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.994435ms","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:5"} {"level":"info","ts":"2026-08-27T19:33:35.725529Z","caller":"traceutil/trace.go:171","msg":"trace[439244990] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:9884; }","duration":"143.075767ms","start":"2026-08-27T19:33:35.582439Z","end":"2026-08-27T19:33:35.725515Z","steps":["trace[439244990] 'count revisions from in-memory index tree' (duration: 142.880332ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:33:35.884121Z","caller":"traceutil/trace.go:171","msg":"trace[1214293206] transaction","detail":"{read_only:false; response_revision:9886; number_of_response:1; }","duration":"152.347164ms","start":"2026-08-27T19:33:35.731724Z","end":"2026-08-27T19:33:35.884072Z","steps":["trace[1214293206] 'process raft request' (duration: 151.907414ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:33:35.884183Z","caller":"traceutil/trace.go:171","msg":"trace[78632970] transaction","detail":"{read_only:false; response_revision:9887; number_of_response:1; }","duration":"152.380325ms","start":"2026-08-27T19:33:35.731759Z","end":"2026-08-27T19:33:35.88414Z","steps":["trace[78632970] 'process raft request' (duration: 152.098328ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:33:35.884228Z","caller":"traceutil/trace.go:171","msg":"trace[916355412] transaction","detail":"{read_only:false; response_revision:9888; number_of_response:1; }","duration":"152.383935ms","start":"2026-08-27T19:33:35.731814Z","end":"2026-08-27T19:33:35.884198Z","steps":["trace[916355412] 'process raft request' (duration: 152.103719ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:34:46.525742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8005} {"level":"info","ts":"2026-08-27T19:34:46.594613Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8005,"took":"66.476098ms","hash":198602070,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":27820032,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-27T19:34:46.594685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":198602070,"revision":8005,"compact-revision":5834} {"level":"warn","ts":"2026-08-27T19:39:16.981141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.686736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-27T19:39:16.981379Z","caller":"traceutil/trace.go:171","msg":"trace[947497198] transaction","detail":"{read_only:false; response_revision:12330; number_of_response:1; }","duration":"159.200837ms","start":"2026-08-27T19:39:16.822153Z","end":"2026-08-27T19:39:16.981354Z","steps":["trace[947497198] 'process raft request' (duration: 21.186303ms)","trace[947497198] 'compare' (duration: 137.536173ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:39:17.034217Z","caller":"traceutil/trace.go:171","msg":"trace[1902970098] transaction","detail":"{read_only:false; response_revision:12331; number_of_response:1; }","duration":"205.382988ms","start":"2026-08-27T19:39:16.828799Z","end":"2026-08-27T19:39:17.034182Z","steps":["trace[1902970098] 'process raft request' (duration: 205.169543ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:39:46.533669Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10373} {"level":"info","ts":"2026-08-27T19:39:46.627769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10373,"took":"91.487157ms","hash":1776971865,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":24924160,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-27T19:39:46.62786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1776971865,"revision":10373,"compact-revision":8005} {"level":"info","ts":"2026-08-27T19:44:46.540807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12538} {"level":"info","ts":"2026-08-27T19:44:46.626285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12538,"took":"82.580569ms","hash":363299129,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":27435008,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-27T19:44:46.626351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":363299129,"revision":12538,"compact-revision":10373} {"level":"info","ts":"2026-08-27T19:45:54.905323Z","caller":"traceutil/trace.go:171","msg":"trace[354148636] transaction","detail":"{read_only:false; response_revision:14973; number_of_response:1; }","duration":"127.60945ms","start":"2026-08-27T19:45:54.777648Z","end":"2026-08-27T19:45:54.905258Z","steps":["trace[354148636] 'process raft request' (duration: 127.269301ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:45:55.026768Z","caller":"traceutil/trace.go:171","msg":"trace[1536362468] transaction","detail":"{read_only:false; response_revision:14974; number_of_response:1; }","duration":"116.749157ms","start":"2026-08-27T19:45:54.90998Z","end":"2026-08-27T19:45:55.026729Z","steps":["trace[1536362468] 'process raft request' (duration: 116.115381ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:46:25.099903Z","caller":"traceutil/trace.go:171","msg":"trace[574282346] transaction","detail":"{read_only:false; response_revision:15262; number_of_response:1; }","duration":"179.097896ms","start":"2026-08-27T19:46:24.920749Z","end":"2026-08-27T19:46:25.099847Z","steps":["trace[574282346] 'process raft request' (duration: 178.738078ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:46:25.327611Z","caller":"traceutil/trace.go:171","msg":"trace[800652583] transaction","detail":"{read_only:false; response_revision:15263; number_of_response:1; }","duration":"219.213497ms","start":"2026-08-27T19:46:25.108364Z","end":"2026-08-27T19:46:25.327578Z","steps":["trace[800652583] 'process raft request' (duration: 218.986642ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:46:25.333587Z","caller":"traceutil/trace.go:171","msg":"trace[1329841060] linearizableReadLoop","detail":"{readStateIndex:15796; appliedIndex:15794; }","duration":"152.281387ms","start":"2026-08-27T19:46:25.181261Z","end":"2026-08-27T19:46:25.333543Z","steps":["trace[1329841060] 'read index received' (duration: 146.146679ms)","trace[1329841060] 'applied index is now lower than readState.Index' (duration: 6.133388ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:46:25.3339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.587635ms","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-27T19:46:25.33395Z","caller":"traceutil/trace.go:171","msg":"trace[43329564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15263; }","duration":"152.738598ms","start":"2026-08-27T19:46:25.1812Z","end":"2026-08-27T19:46:25.333939Z","steps":["trace[43329564] 'agreement among raft nodes before linearized reading' (duration: 152.491491ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:31.049323Z","caller":"traceutil/trace.go:171","msg":"trace[1151814427] transaction","detail":"{read_only:false; response_revision:16167; number_of_response:1; }","duration":"176.71264ms","start":"2026-08-27T19:47:30.872585Z","end":"2026-08-27T19:47:31.049298Z","steps":["trace[1151814427] 'process raft request' (duration: 176.546077ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:31.06403Z","caller":"traceutil/trace.go:171","msg":"trace[2092775076] linearizableReadLoop","detail":"{readStateIndex:16731; appliedIndex:16728; }","duration":"171.175214ms","start":"2026-08-27T19:47:30.892813Z","end":"2026-08-27T19:47:31.063988Z","steps":["trace[2092775076] 'read index received' (duration: 156.418778ms)","trace[2092775076] 'applied index is now lower than readState.Index' (duration: 14.754676ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:47:31.06408Z","caller":"traceutil/trace.go:171","msg":"trace[1478892613] transaction","detail":"{read_only:false; response_revision:16169; number_of_response:1; }","duration":"185.851752ms","start":"2026-08-27T19:47:30.878207Z","end":"2026-08-27T19:47:31.064059Z","steps":["trace[1478892613] 'process raft request' (duration: 185.685027ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:31.064122Z","caller":"traceutil/trace.go:171","msg":"trace[208908101] transaction","detail":"{read_only:false; response_revision:16168; number_of_response:1; }","duration":"185.868622ms","start":"2026-08-27T19:47:30.878207Z","end":"2026-08-27T19:47:31.064076Z","steps":["trace[208908101] 'process raft request' (duration: 185.470862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:31.064303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.459173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:47:31.064412Z","caller":"traceutil/trace.go:171","msg":"trace[613000910] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16169; }","duration":"171.615095ms","start":"2026-08-27T19:47:30.892776Z","end":"2026-08-27T19:47:31.064391Z","steps":["trace[613000910] 'agreement among raft nodes before linearized reading' (duration: 171.400431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:31.064441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.401761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-27T19:47:31.064546Z","caller":"traceutil/trace.go:171","msg":"trace[1760673515] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16169; }","duration":"171.566375ms","start":"2026-08-27T19:47:30.89296Z","end":"2026-08-27T19:47:31.064526Z","steps":["trace[1760673515] 'agreement among raft nodes before linearized reading' (duration: 171.275498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:31.064822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.77767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:47:31.064898Z","caller":"traceutil/trace.go:171","msg":"trace[2042284087] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16169; }","duration":"171.896892ms","start":"2026-08-27T19:47:30.892985Z","end":"2026-08-27T19:47:31.064882Z","steps":["trace[2042284087] 'agreement among raft nodes before linearized reading' (duration: 171.758739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:31.064675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.412627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-27T19:47:31.064969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.735107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:22 size:199000"} {"level":"info","ts":"2026-08-27T19:47:31.06506Z","caller":"traceutil/trace.go:171","msg":"trace[1896059660] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:22; response_revision:16169; }","duration":"130.864099ms","start":"2026-08-27T19:47:30.934173Z","end":"2026-08-27T19:47:31.065037Z","steps":["trace[1896059660] 'agreement among raft nodes before linearized reading' (duration: 130.350747ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:31.06528Z","caller":"traceutil/trace.go:171","msg":"trace[2027615905] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16169; }","duration":"163.752896ms","start":"2026-08-27T19:47:30.901219Z","end":"2026-08-27T19:47:31.064972Z","steps":["trace[2027615905] 'agreement among raft nodes before linearized reading' (duration: 163.164891ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:36.13782Z","caller":"traceutil/trace.go:171","msg":"trace[1025653230] linearizableReadLoop","detail":"{readStateIndex:16759; appliedIndex:16758; }","duration":"124.787241ms","start":"2026-08-27T19:47:36.012999Z","end":"2026-08-27T19:47:36.137786Z","steps":["trace[1025653230] 'read index received' (duration: 124.575416ms)","trace[1025653230] 'applied index is now lower than readState.Index' (duration: 210.525µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:47:36.137887Z","caller":"traceutil/trace.go:171","msg":"trace[50173665] transaction","detail":"{read_only:false; response_revision:16196; number_of_response:1; }","duration":"191.468137ms","start":"2026-08-27T19:47:35.946361Z","end":"2026-08-27T19:47:36.137829Z","steps":["trace[50173665] 'process raft request' (duration: 191.239762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:36.13814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.11381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-08-27T19:47:36.138192Z","caller":"traceutil/trace.go:171","msg":"trace[1315324695] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16196; }","duration":"125.239544ms","start":"2026-08-27T19:47:36.012936Z","end":"2026-08-27T19:47:36.138175Z","steps":["trace[1315324695] 'agreement among raft nodes before linearized reading' (duration: 125.046898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:36.138288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.074901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-08-27T19:47:36.13832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.048768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:47:36.13839Z","caller":"traceutil/trace.go:171","msg":"trace[1527011311] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16196; }","duration":"123.259425ms","start":"2026-08-27T19:47:36.01511Z","end":"2026-08-27T19:47:36.138369Z","steps":["trace[1527011311] 'agreement among raft nodes before linearized reading' (duration: 123.00588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:36.138212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.088059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:47:36.138442Z","caller":"traceutil/trace.go:171","msg":"trace[884237635] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16196; }","duration":"125.222262ms","start":"2026-08-27T19:47:36.013191Z","end":"2026-08-27T19:47:36.138414Z","steps":["trace[884237635] 'agreement among raft nodes before linearized reading' (duration: 125.002077ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:36.138467Z","caller":"traceutil/trace.go:171","msg":"trace[502856904] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16196; }","duration":"125.422857ms","start":"2026-08-27T19:47:36.013031Z","end":"2026-08-27T19:47:36.138454Z","steps":["trace[502856904] 'agreement among raft nodes before linearized reading' (duration: 125.070819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:36.138547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.343915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-27T19:47:36.138674Z","caller":"traceutil/trace.go:171","msg":"trace[1261246726] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16196; }","duration":"125.54559ms","start":"2026-08-27T19:47:36.013097Z","end":"2026-08-27T19:47:36.138643Z","steps":["trace[1261246726] 'agreement among raft nodes before linearized reading' (duration: 125.13885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:36.483232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.120885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-nk6kz\" ","response":"range_response_count:1 size:22010"} {"level":"warn","ts":"2026-08-27T19:47:36.48332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.179424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-27T19:47:36.483349Z","caller":"traceutil/trace.go:171","msg":"trace[483854634] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-nk6kz; range_end:; response_count:1; response_revision:16196; }","duration":"200.30374ms","start":"2026-08-27T19:47:36.283018Z","end":"2026-08-27T19:47:36.483322Z","steps":["trace[483854634] 'range keys from in-memory index tree' (duration: 199.830449ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:36.483372Z","caller":"traceutil/trace.go:171","msg":"trace[1449610770] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:16196; }","duration":"154.264715ms","start":"2026-08-27T19:47:36.329092Z","end":"2026-08-27T19:47:36.483357Z","steps":["trace[1449610770] 'count revisions from in-memory index tree' (duration: 154.110062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:36.483367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.560481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-nk6kz\" ","response":"range_response_count:1 size:22010"} {"level":"info","ts":"2026-08-27T19:47:36.483448Z","caller":"traceutil/trace.go:171","msg":"trace[209198445] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-nk6kz; range_end:; response_count:1; response_revision:16196; }","duration":"239.707414ms","start":"2026-08-27T19:47:36.243722Z","end":"2026-08-27T19:47:36.483429Z","steps":["trace[209198445] 'range keys from in-memory index tree' (duration: 239.351546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:36.483552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.213571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-27T19:47:36.483698Z","caller":"traceutil/trace.go:171","msg":"trace[330722886] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16196; }","duration":"172.439187ms","start":"2026-08-27T19:47:36.311232Z","end":"2026-08-27T19:47:36.483671Z","steps":["trace[330722886] 'range keys from in-memory index tree' (duration: 171.881032ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:37.213277Z","caller":"traceutil/trace.go:171","msg":"trace[982761792] transaction","detail":"{read_only:false; response_revision:16198; number_of_response:1; }","duration":"388.075669ms","start":"2026-08-27T19:47:36.825174Z","end":"2026-08-27T19:47:37.21325Z","steps":["trace[982761792] 'process raft request' (duration: 387.903205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.213451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:47:36.825156Z","time spent":"388.202701ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-27T19:47:37.285604Z","caller":"traceutil/trace.go:171","msg":"trace[1439548839] transaction","detail":"{read_only:false; response_revision:16199; number_of_response:1; }","duration":"458.406831ms","start":"2026-08-27T19:47:36.827175Z","end":"2026-08-27T19:47:37.285582Z","steps":["trace[1439548839] 'process raft request' (duration: 446.00912ms)","trace[1439548839] 'compare' (duration: 11.98608ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:47:37.285734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:47:36.827152Z","time spent":"458.501263ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-27T19:47:37.285753Z","caller":"traceutil/trace.go:171","msg":"trace[115521691] linearizableReadLoop","detail":"{readStateIndex:16762; appliedIndex:16760; }","duration":"400.837997ms","start":"2026-08-27T19:47:36.884858Z","end":"2026-08-27T19:47:37.285696Z","steps":["trace[115521691] 'read index received' (duration: 328.202398ms)","trace[115521691] 'applied index is now lower than readState.Index' (duration: 72.631219ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:47:37.286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.123024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:47:37.286205Z","caller":"traceutil/trace.go:171","msg":"trace[1262668899] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:16201; }","duration":"401.393861ms","start":"2026-08-27T19:47:36.884786Z","end":"2026-08-27T19:47:37.28618Z","steps":["trace[1262668899] 'agreement among raft nodes before linearized reading' (duration: 401.057283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.286338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:47:36.884762Z","time spent":"401.557644ms","remote":"127.0.0.1:59090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-27T19:47:37.289083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.165959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:47:37.289172Z","caller":"traceutil/trace.go:171","msg":"trace[1767198414] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16202; }","duration":"388.288652ms","start":"2026-08-27T19:47:36.900859Z","end":"2026-08-27T19:47:37.289148Z","steps":["trace[1767198414] 'agreement among raft nodes before linearized reading' (duration: 388.133409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.289165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.407684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-27T19:47:37.289214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:47:36.900845Z","time spent":"388.360725ms","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"info","ts":"2026-08-27T19:47:37.289261Z","caller":"traceutil/trace.go:171","msg":"trace[1620979083] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16202; }","duration":"198.517727ms","start":"2026-08-27T19:47:37.090719Z","end":"2026-08-27T19:47:37.289237Z","steps":["trace[1620979083] 'agreement among raft nodes before linearized reading' (duration: 198.340212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.289354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.30067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-27T19:47:37.289375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.443516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-08-27T19:47:37.289382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.53974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-27T19:47:37.289419Z","caller":"traceutil/trace.go:171","msg":"trace[403847] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16202; }","duration":"198.495207ms","start":"2026-08-27T19:47:37.090908Z","end":"2026-08-27T19:47:37.289404Z","steps":["trace[403847] 'agreement among raft nodes before linearized reading' (duration: 198.321603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.289437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.911725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:47:37.289446Z","caller":"traceutil/trace.go:171","msg":"trace[1991696203] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16202; }","duration":"386.731525ms","start":"2026-08-27T19:47:36.902699Z","end":"2026-08-27T19:47:37.28943Z","steps":["trace[1991696203] 'agreement among raft nodes before linearized reading' (duration: 386.548341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.289094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.433757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-27T19:47:37.289522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.938715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-08-27T19:47:37.289534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.763199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-27T19:47:37.28961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.13882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-27T19:47:37.289609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.402914ms","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-27T19:47:37.28961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.969191ms","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-27T19:47:37.289618Z","caller":"traceutil/trace.go:171","msg":"trace[258915558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16202; }","duration":"166.865382ms","start":"2026-08-27T19:47:37.122731Z","end":"2026-08-27T19:47:37.289597Z","steps":["trace[258915558] 'agreement among raft nodes before linearized reading' (duration: 166.649917ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:37.289635Z","caller":"traceutil/trace.go:171","msg":"trace[1485841841] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16202; }","duration":"196.16362ms","start":"2026-08-27T19:47:37.093464Z","end":"2026-08-27T19:47:37.289628Z","steps":["trace[1485841841] 'agreement among raft nodes before linearized reading' (duration: 196.12722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.289633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.420653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-p4pv2\" ","response":"range_response_count:1 size:9711"} {"level":"info","ts":"2026-08-27T19:47:37.289645Z","caller":"traceutil/trace.go:171","msg":"trace[1598218556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16202; }","duration":"333.461085ms","start":"2026-08-27T19:47:36.956173Z","end":"2026-08-27T19:47:37.289634Z","steps":["trace[1598218556] 'agreement among raft nodes before linearized reading' (duration: 333.341482ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:37.289648Z","caller":"traceutil/trace.go:171","msg":"trace[170542306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16202; }","duration":"164.031093ms","start":"2026-08-27T19:47:37.125609Z","end":"2026-08-27T19:47:37.28964Z","steps":["trace[170542306] 'agreement among raft nodes before linearized reading' (duration: 163.90948ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:37.289621Z","caller":"traceutil/trace.go:171","msg":"trace[261577497] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16202; }","duration":"196.945539ms","start":"2026-08-27T19:47:37.09259Z","end":"2026-08-27T19:47:37.289536Z","steps":["trace[261577497] 'agreement among raft nodes before linearized reading' (duration: 196.428426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.289689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:47:36.956158Z","time spent":"333.524987ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-27T19:47:37.289689Z","caller":"traceutil/trace.go:171","msg":"trace[687847037] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-p4pv2; range_end:; response_count:1; response_revision:16202; }","duration":"143.505605ms","start":"2026-08-27T19:47:37.14617Z","end":"2026-08-27T19:47:37.289675Z","steps":["trace[687847037] 'agreement among raft nodes before linearized reading' (duration: 143.310451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.289189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.317392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:47:37.289551Z","caller":"traceutil/trace.go:171","msg":"trace[118505540] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16202; }","duration":"196.011446ms","start":"2026-08-27T19:47:37.093531Z","end":"2026-08-27T19:47:37.289543Z","steps":["trace[118505540] 'agreement among raft nodes before linearized reading' (duration: 195.893964ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:37.289745Z","caller":"traceutil/trace.go:171","msg":"trace[2052187245] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16202; }","duration":"198.953188ms","start":"2026-08-27T19:47:37.090781Z","end":"2026-08-27T19:47:37.289735Z","steps":["trace[2052187245] 'agreement among raft nodes before linearized reading' (duration: 198.320932ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:37.289489Z","caller":"traceutil/trace.go:171","msg":"trace[586546833] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16202; }","duration":"388.007317ms","start":"2026-08-27T19:47:36.901457Z","end":"2026-08-27T19:47:37.289465Z","steps":["trace[586546833] 'agreement among raft nodes before linearized reading' (duration: 387.935885ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:37.289405Z","caller":"traceutil/trace.go:171","msg":"trace[249965795] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:16202; }","duration":"119.372871ms","start":"2026-08-27T19:47:37.170015Z","end":"2026-08-27T19:47:37.289388Z","steps":["trace[249965795] 'agreement among raft nodes before linearized reading' (duration: 119.297039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.289819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:47:36.901431Z","time spent":"388.380806ms","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-08-27T19:47:37.289811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.493108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-27T19:47:37.289483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:47:36.902673Z","time spent":"386.800817ms","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5470,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-08-27T19:47:37.289858Z","caller":"traceutil/trace.go:171","msg":"trace[1117454258] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16202; }","duration":"388.557729ms","start":"2026-08-27T19:47:36.901288Z","end":"2026-08-27T19:47:37.289845Z","steps":["trace[1117454258] 'agreement among raft nodes before linearized reading' (duration: 388.489008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:37.289938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:47:36.901276Z","time spent":"388.652161ms","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-08-27T19:47:37.289955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.160593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-27T19:47:37.290129Z","caller":"traceutil/trace.go:171","msg":"trace[442290407] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16202; }","duration":"199.363667ms","start":"2026-08-27T19:47:37.090726Z","end":"2026-08-27T19:47:37.290089Z","steps":["trace[442290407] 'agreement among raft nodes before linearized reading' (duration: 198.466746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:47:59.771083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.547667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-p4pv2\" ","response":"range_response_count:1 size:9711"} {"level":"warn","ts":"2026-08-27T19:47:59.771178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.898379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:47:59.771217Z","caller":"traceutil/trace.go:171","msg":"trace[1815824823] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-p4pv2; range_end:; response_count:1; response_revision:16323; }","duration":"124.756022ms","start":"2026-08-27T19:47:59.646433Z","end":"2026-08-27T19:47:59.771189Z","steps":["trace[1815824823] 'range keys from in-memory index tree' (duration: 124.230668ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:59.77129Z","caller":"traceutil/trace.go:171","msg":"trace[1751947823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16323; }","duration":"138.069384ms","start":"2026-08-27T19:47:59.63319Z","end":"2026-08-27T19:47:59.771259Z","steps":["trace[1751947823] 'range keys from in-memory index tree' (duration: 137.798188ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:47:59.968985Z","caller":"traceutil/trace.go:171","msg":"trace[506786605] transaction","detail":"{read_only:false; response_revision:16324; number_of_response:1; }","duration":"160.166539ms","start":"2026-08-27T19:47:59.808786Z","end":"2026-08-27T19:47:59.968953Z","steps":["trace[506786605] 'process raft request' (duration: 159.912022ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:48:00.1492Z","caller":"traceutil/trace.go:171","msg":"trace[732801998] linearizableReadLoop","detail":"{readStateIndex:16892; appliedIndex:16891; }","duration":"174.616009ms","start":"2026-08-27T19:47:59.974553Z","end":"2026-08-27T19:48:00.149169Z","steps":["trace[732801998] 'read index received' (duration: 116.085101ms)","trace[732801998] 'applied index is now lower than readState.Index' (duration: 58.529118ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:48:00.149464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.901935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r7zlr\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-08-27T19:48:00.149504Z","caller":"traceutil/trace.go:171","msg":"trace[846698052] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r7zlr; range_end:; response_count:1; response_revision:16324; }","duration":"175.001047ms","start":"2026-08-27T19:47:59.974493Z","end":"2026-08-27T19:48:00.149494Z","steps":["trace[846698052] 'agreement among raft nodes before linearized reading' (duration: 174.838274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:00.149783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.992827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-27T19:48:00.149988Z","caller":"traceutil/trace.go:171","msg":"trace[994049176] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:16324; }","duration":"123.260964ms","start":"2026-08-27T19:48:00.026707Z","end":"2026-08-27T19:48:00.149968Z","steps":["trace[994049176] 'agreement among raft nodes before linearized reading' (duration: 122.918327ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:48:00.274197Z","caller":"traceutil/trace.go:171","msg":"trace[1652923302] linearizableReadLoop","detail":"{readStateIndex:16893; appliedIndex:16892; }","duration":"119.321179ms","start":"2026-08-27T19:48:00.154858Z","end":"2026-08-27T19:48:00.274179Z","steps":["trace[1652923302] 'read index received' (duration: 96.153398ms)","trace[1652923302] 'applied index is now lower than readState.Index' (duration: 23.166721ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:48:00.27429Z","caller":"traceutil/trace.go:171","msg":"trace[1640747750] transaction","detail":"{read_only:false; response_revision:16325; number_of_response:1; }","duration":"123.769418ms","start":"2026-08-27T19:48:00.150487Z","end":"2026-08-27T19:48:00.274256Z","steps":["trace[1640747750] 'process raft request' (duration: 100.609977ms)","trace[1640747750] 'compare' (duration: 22.957645ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:48:00.275208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.335345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764131"} {"level":"info","ts":"2026-08-27T19:48:00.275257Z","caller":"traceutil/trace.go:171","msg":"trace[1549835248] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16325; }","duration":"120.422276ms","start":"2026-08-27T19:48:00.154824Z","end":"2026-08-27T19:48:00.275247Z","steps":["trace[1549835248] 'agreement among raft nodes before linearized reading' (duration: 119.458153ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:48:01.577248Z","caller":"traceutil/trace.go:171","msg":"trace[317727052] transaction","detail":"{read_only:false; response_revision:16330; number_of_response:1; }","duration":"163.774286ms","start":"2026-08-27T19:48:01.413428Z","end":"2026-08-27T19:48:01.577202Z","steps":["trace[317727052] 'process raft request' (duration: 163.683194ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:48:01.57727Z","caller":"traceutil/trace.go:171","msg":"trace[129970937] transaction","detail":"{read_only:false; response_revision:16329; number_of_response:1; }","duration":"169.244398ms","start":"2026-08-27T19:48:01.407973Z","end":"2026-08-27T19:48:01.577218Z","steps":["trace[129970937] 'process raft request' (duration: 147.231306ms)","trace[129970937] 'compare' (duration: 21.766197ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:48:01.577479Z","caller":"traceutil/trace.go:171","msg":"trace[853233433] transaction","detail":"{read_only:false; response_revision:16331; number_of_response:1; }","duration":"148.287061ms","start":"2026-08-27T19:48:01.429166Z","end":"2026-08-27T19:48:01.577453Z","steps":["trace[853233433] 'process raft request' (duration: 147.996953ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:48:01.577485Z","caller":"traceutil/trace.go:171","msg":"trace[1939799708] linearizableReadLoop","detail":"{readStateIndex:16899; appliedIndex:16896; }","duration":"125.175613ms","start":"2026-08-27T19:48:01.452271Z","end":"2026-08-27T19:48:01.577446Z","steps":["trace[1939799708] 'read index received' (duration: 102.877432ms)","trace[1939799708] 'applied index is now lower than readState.Index' (duration: 22.295941ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:48:01.57764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.211301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:48:01.577711Z","caller":"traceutil/trace.go:171","msg":"trace[1571145887] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16331; }","duration":"172.333004ms","start":"2026-08-27T19:48:01.405353Z","end":"2026-08-27T19:48:01.577685Z","steps":["trace[1571145887] 'agreement among raft nodes before linearized reading' (duration: 172.186271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:01.577641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.283749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-08-27T19:48:01.577787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.237567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-27T19:48:01.577793Z","caller":"traceutil/trace.go:171","msg":"trace[923095813] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16331; }","duration":"164.443473ms","start":"2026-08-27T19:48:01.413314Z","end":"2026-08-27T19:48:01.577757Z","steps":["trace[923095813] 'agreement among raft nodes before linearized reading' (duration: 164.199407ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:48:01.577831Z","caller":"traceutil/trace.go:171","msg":"trace[1670678131] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16331; }","duration":"164.291389ms","start":"2026-08-27T19:48:01.413526Z","end":"2026-08-27T19:48:01.577818Z","steps":["trace[1670678131] 'agreement among raft nodes before linearized reading' (duration: 164.213787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:01.57785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.688628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:48:01.577935Z","caller":"traceutil/trace.go:171","msg":"trace[1132683439] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16331; }","duration":"171.816311ms","start":"2026-08-27T19:48:01.406106Z","end":"2026-08-27T19:48:01.577922Z","steps":["trace[1132683439] 'agreement among raft nodes before linearized reading' (duration: 171.687328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:01.577935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.019917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-08-27T19:48:01.577719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.707003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-27T19:48:01.577995Z","caller":"traceutil/trace.go:171","msg":"trace[1082461103] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16331; }","duration":"132.15921ms","start":"2026-08-27T19:48:01.445819Z","end":"2026-08-27T19:48:01.577978Z","steps":["trace[1082461103] 'agreement among raft nodes before linearized reading' (duration: 131.954465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:01.577991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.14499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:48:01.578025Z","caller":"traceutil/trace.go:171","msg":"trace[2007410580] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16331; }","duration":"128.02076ms","start":"2026-08-27T19:48:01.449986Z","end":"2026-08-27T19:48:01.578007Z","steps":["trace[2007410580] 'agreement among raft nodes before linearized reading' (duration: 127.679832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:01.577999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.467467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:48:01.578058Z","caller":"traceutil/trace.go:171","msg":"trace[1395979456] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16331; }","duration":"132.439477ms","start":"2026-08-27T19:48:01.445599Z","end":"2026-08-27T19:48:01.578038Z","steps":["trace[1395979456] 'agreement among raft nodes before linearized reading' (duration: 132.13084ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:48:01.578119Z","caller":"traceutil/trace.go:171","msg":"trace[1776412542] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16331; }","duration":"172.652791ms","start":"2026-08-27T19:48:01.405435Z","end":"2026-08-27T19:48:01.578087Z","steps":["trace[1776412542] 'agreement among raft nodes before linearized reading' (duration: 172.398846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:01.578132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.902353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-08-27T19:48:01.578132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.74865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-27T19:48:01.578107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.930434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:48:01.578198Z","caller":"traceutil/trace.go:171","msg":"trace[707384013] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16331; }","duration":"164.850561ms","start":"2026-08-27T19:48:01.413328Z","end":"2026-08-27T19:48:01.578178Z","steps":["trace[707384013] 'agreement among raft nodes before linearized reading' (duration: 164.697328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:01.578207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.844606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:48:01.578166Z","caller":"traceutil/trace.go:171","msg":"trace[1792610859] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16331; }","duration":"164.949324ms","start":"2026-08-27T19:48:01.413206Z","end":"2026-08-27T19:48:01.578156Z","steps":["trace[1792610859] 'agreement among raft nodes before linearized reading' (duration: 164.809081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:01.578251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.491162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-27T19:48:01.578294Z","caller":"traceutil/trace.go:171","msg":"trace[1624116078] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16331; }","duration":"165.946538ms","start":"2026-08-27T19:48:01.412323Z","end":"2026-08-27T19:48:01.578269Z","steps":["trace[1624116078] 'agreement among raft nodes before linearized reading' (duration: 165.773814ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:48:01.578303Z","caller":"traceutil/trace.go:171","msg":"trace[1992584015] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16331; }","duration":"171.563704ms","start":"2026-08-27T19:48:01.406725Z","end":"2026-08-27T19:48:01.578289Z","steps":["trace[1992584015] 'agreement among raft nodes before linearized reading' (duration: 171.425911ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:48:01.578229Z","caller":"traceutil/trace.go:171","msg":"trace[1536676216] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16331; }","duration":"172.091607ms","start":"2026-08-27T19:48:01.406111Z","end":"2026-08-27T19:48:01.578202Z","steps":["trace[1536676216] 'agreement among raft nodes before linearized reading' (duration: 171.883972ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:49:46.548597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14540} {"level":"info","ts":"2026-08-27T19:49:46.644692Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14540,"took":"92.743768ms","hash":1429177806,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-27T19:49:46.644767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1429177806,"revision":14540,"compact-revision":12538} {"level":"info","ts":"2026-08-27T19:53:49.61397Z","caller":"traceutil/trace.go:171","msg":"trace[1383434250] transaction","detail":"{read_only:false; response_revision:18804; number_of_response:1; }","duration":"203.084649ms","start":"2026-08-27T19:53:49.41085Z","end":"2026-08-27T19:53:49.613934Z","steps":["trace[1383434250] 'process raft request' (duration: 202.875973ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:53:50.125002Z","caller":"traceutil/trace.go:171","msg":"trace[1405418351] linearizableReadLoop","detail":"{readStateIndex:19457; appliedIndex:19456; }","duration":"206.189034ms","start":"2026-08-27T19:53:49.918778Z","end":"2026-08-27T19:53:50.124967Z","steps":["trace[1405418351] 'read index received' (duration: 205.919197ms)","trace[1405418351] 'applied index is now lower than readState.Index' (duration: 268.387µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:53:50.125129Z","caller":"traceutil/trace.go:171","msg":"trace[136679277] transaction","detail":"{read_only:false; response_revision:18808; number_of_response:1; }","duration":"388.754066ms","start":"2026-08-27T19:53:49.736338Z","end":"2026-08-27T19:53:50.125092Z","steps":["trace[136679277] 'process raft request' (duration: 388.408988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:53:50.125346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:53:49.736299Z","time spent":"388.92467ms","remote":"127.0.0.1:59050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20774,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:53:50.125362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.038741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-27T19:53:50.125403Z","caller":"traceutil/trace.go:171","msg":"trace[1450998212] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:18808; }","duration":"182.132562ms","start":"2026-08-27T19:53:49.943258Z","end":"2026-08-27T19:53:50.125391Z","steps":["trace[1450998212] 'agreement among raft nodes before linearized reading' (duration: 181.904587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:53:50.12546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.636364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-27T19:53:50.125606Z","caller":"traceutil/trace.go:171","msg":"trace[1773246913] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:18808; }","duration":"206.85203ms","start":"2026-08-27T19:53:49.918727Z","end":"2026-08-27T19:53:50.125579Z","steps":["trace[1773246913] 'agreement among raft nodes before linearized reading' (duration: 206.407088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:53:50.125605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.023755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7309"} {"level":"warn","ts":"2026-08-27T19:53:50.125633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.053214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-27T19:53:50.125723Z","caller":"traceutil/trace.go:171","msg":"trace[1471670293] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18808; }","duration":"115.21269ms","start":"2026-08-27T19:53:50.010485Z","end":"2026-08-27T19:53:50.125698Z","steps":["trace[1471670293] 'agreement among raft nodes before linearized reading' (duration: 114.83958ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:53:50.125738Z","caller":"traceutil/trace.go:171","msg":"trace[339504008] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18808; }","duration":"122.176297ms","start":"2026-08-27T19:53:50.003536Z","end":"2026-08-27T19:53:50.125712Z","steps":["trace[339504008] 'agreement among raft nodes before linearized reading' (duration: 121.842208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:53:50.633225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995353835505998013,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-27T19:53:50.651916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:53:50.128266Z","time spent":"523.641729ms","remote":"127.0.0.1:58864","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-27T19:53:50.675809Z","caller":"traceutil/trace.go:171","msg":"trace[166060970] linearizableReadLoop","detail":"{readStateIndex:19459; appliedIndex:19458; }","duration":"543.255982ms","start":"2026-08-27T19:53:50.132526Z","end":"2026-08-27T19:53:50.675782Z","steps":["trace[166060970] 'read index received' (duration: 519.382696ms)","trace[166060970] 'applied index is now lower than readState.Index' (duration: 23.871866ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:53:50.675889Z","caller":"traceutil/trace.go:171","msg":"trace[2014944385] transaction","detail":"{read_only:false; response_revision:18809; number_of_response:1; }","duration":"512.738936ms","start":"2026-08-27T19:53:50.163106Z","end":"2026-08-27T19:53:50.675845Z","steps":["trace[2014944385] 'process raft request' (duration: 512.47587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:53:50.676093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:53:50.16308Z","time spent":"512.89481ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:53:50.676892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.360449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:933658"} {"level":"info","ts":"2026-08-27T19:53:50.67694Z","caller":"traceutil/trace.go:171","msg":"trace[476367946] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18809; }","duration":"544.471492ms","start":"2026-08-27T19:53:50.132458Z","end":"2026-08-27T19:53:50.676929Z","steps":["trace[476367946] 'agreement among raft nodes before linearized reading' (duration: 543.460287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:53:50.677002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:53:50.13244Z","time spent":"544.550903ms","remote":"127.0.0.1:59050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":933680,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-27T19:53:50.722785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.800896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7309"} {"level":"info","ts":"2026-08-27T19:53:50.722891Z","caller":"traceutil/trace.go:171","msg":"trace[1957757414] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18810; }","duration":"570.952439ms","start":"2026-08-27T19:53:50.151914Z","end":"2026-08-27T19:53:50.722867Z","steps":["trace[1957757414] 'agreement among raft nodes before linearized reading' (duration: 570.630522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:53:50.722935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:53:50.151903Z","time spent":"571.023222ms","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":7331,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"warn","ts":"2026-08-27T19:53:50.723624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.776449ms","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-27T19:53:50.723656Z","caller":"traceutil/trace.go:171","msg":"trace[1309121441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18810; }","duration":"362.85917ms","start":"2026-08-27T19:53:50.360786Z","end":"2026-08-27T19:53:50.723646Z","steps":["trace[1309121441] 'agreement among raft nodes before linearized reading' (duration: 362.774198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:53:50.723678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:53:50.360762Z","time spent":"362.909643ms","remote":"127.0.0.1:59130","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":"info","ts":"2026-08-27T19:54:00.426464Z","caller":"traceutil/trace.go:171","msg":"trace[1196132725] transaction","detail":"{read_only:false; response_revision:18946; number_of_response:1; }","duration":"113.091587ms","start":"2026-08-27T19:54:00.313337Z","end":"2026-08-27T19:54:00.426429Z","steps":["trace[1196132725] 'process raft request' (duration: 105.676338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:54:00.735701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.66162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T19:54:00.735792Z","caller":"traceutil/trace.go:171","msg":"trace[1065104530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18960; }","duration":"103.790834ms","start":"2026-08-27T19:54:00.631979Z","end":"2026-08-27T19:54:00.73577Z","steps":["trace[1065104530] 'agreement among raft nodes before linearized reading' (duration: 103.63974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:54:00.735858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.177493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-27T19:54:00.735952Z","caller":"traceutil/trace.go:171","msg":"trace[1373824093] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:18960; }","duration":"109.291066ms","start":"2026-08-27T19:54:00.626636Z","end":"2026-08-27T19:54:00.735927Z","steps":["trace[1373824093] 'agreement among raft nodes before linearized reading' (duration: 109.038949ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:54:00.873565Z","caller":"traceutil/trace.go:171","msg":"trace[1759734980] transaction","detail":"{read_only:false; response_revision:18962; number_of_response:1; }","duration":"121.421659ms","start":"2026-08-27T19:54:00.752081Z","end":"2026-08-27T19:54:00.873502Z","steps":["trace[1759734980] 'process raft request' (duration: 48.247324ms)","trace[1759734980] 'compare' (duration: 72.863608ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:54:00.873571Z","caller":"traceutil/trace.go:171","msg":"trace[1579527776] linearizableReadLoop","detail":"{readStateIndex:19619; appliedIndex:19618; }","duration":"115.209219ms","start":"2026-08-27T19:54:00.758285Z","end":"2026-08-27T19:54:00.873495Z","steps":["trace[1579527776] 'read index received' (duration: 42.027744ms)","trace[1579527776] 'applied index is now lower than readState.Index' (duration: 73.170214ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:54:00.8738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.492065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"info","ts":"2026-08-27T19:54:00.873908Z","caller":"traceutil/trace.go:171","msg":"trace[1673416758] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:18962; }","duration":"115.654529ms","start":"2026-08-27T19:54:00.758234Z","end":"2026-08-27T19:54:00.873889Z","steps":["trace[1673416758] 'agreement among raft nodes before linearized reading' (duration: 115.339361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:54:00.87395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.960843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T19:54:00.874026Z","caller":"traceutil/trace.go:171","msg":"trace[437377382] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:18962; }","duration":"115.130377ms","start":"2026-08-27T19:54:00.758869Z","end":"2026-08-27T19:54:00.874Z","steps":["trace[437377382] 'agreement among raft nodes before linearized reading' (duration: 114.904261ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:54:39.948872Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"14f8f2dfdaccebdb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-27T19:54:39.956035Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-27T19:54:39.956199Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-27T19:54:46.554796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16972} {"level":"info","ts":"2026-08-27T19:54:46.665066Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16972,"took":"106.324304ms","hash":2261234123,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":32673792,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-27T19:54:46.665135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2261234123,"revision":16972,"compact-revision":14540} {"level":"info","ts":"2026-08-27T19:59:46.566545Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19392} {"level":"info","ts":"2026-08-27T19:59:46.692135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19392,"took":"121.301042ms","hash":2477144031,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":30142464,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-27T19:59:46.692215Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2477144031,"revision":19392,"compact-revision":16972} {"level":"info","ts":"2026-08-27T20:00:48.58305Z","caller":"traceutil/trace.go:171","msg":"trace[1570966644] transaction","detail":"{read_only:false; response_revision:22069; number_of_response:1; }","duration":"100.650153ms","start":"2026-08-27T20:00:48.482376Z","end":"2026-08-27T20:00:48.583026Z","steps":["trace[1570966644] 'process raft request' (duration: 100.487039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:00:48.955633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.889973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T20:00:48.955736Z","caller":"traceutil/trace.go:171","msg":"trace[1860930281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22069; }","duration":"323.059257ms","start":"2026-08-27T20:00:48.632655Z","end":"2026-08-27T20:00:48.955714Z","steps":["trace[1860930281] 'range keys from in-memory index tree' (duration: 322.73787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:00:48.955775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:00:48.632631Z","time spent":"323.134759ms","remote":"127.0.0.1:58838","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-27T20:00:48.955915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.434127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-27T20:00:48.956072Z","caller":"traceutil/trace.go:171","msg":"trace[1797430415] transaction","detail":"{read_only:false; response_revision:22070; number_of_response:1; }","duration":"230.589862ms","start":"2026-08-27T20:00:48.725455Z","end":"2026-08-27T20:00:48.956045Z","steps":["trace[1797430415] 'process raft request' (duration: 45.86473ms)","trace[1797430415] 'compare' (duration: 184.133219ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T20:00:59.392407Z","caller":"traceutil/trace.go:171","msg":"trace[911137080] transaction","detail":"{read_only:false; response_revision:22124; number_of_response:1; }","duration":"134.251745ms","start":"2026-08-27T20:00:59.258125Z","end":"2026-08-27T20:00:59.392377Z","steps":["trace[911137080] 'process raft request' (duration: 134.05526ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:01:04.874695Z","caller":"traceutil/trace.go:171","msg":"trace[1440007450] linearizableReadLoop","detail":"{readStateIndex:22907; appliedIndex:22906; }","duration":"208.664712ms","start":"2026-08-27T20:01:04.666001Z","end":"2026-08-27T20:01:04.874666Z","steps":["trace[1440007450] 'read index received' (duration: 208.319924ms)","trace[1440007450] 'applied index is now lower than readState.Index' (duration: 343.418µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T20:01:04.874875Z","caller":"traceutil/trace.go:171","msg":"trace[835567127] transaction","detail":"{read_only:false; response_revision:22150; number_of_response:1; }","duration":"222.802854ms","start":"2026-08-27T20:01:04.652025Z","end":"2026-08-27T20:01:04.874828Z","steps":["trace[835567127] 'process raft request' (duration: 222.357713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:01:04.875027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.975191ms","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-27T20:01:04.87526Z","caller":"traceutil/trace.go:171","msg":"trace[1165061526] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:22150; }","duration":"209.255787ms","start":"2026-08-27T20:01:04.665932Z","end":"2026-08-27T20:01:04.875187Z","steps":["trace[1165061526] 'agreement among raft nodes before linearized reading' (duration: 208.95864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:01:05.1202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.298149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-27T20:01:05.120352Z","caller":"traceutil/trace.go:171","msg":"trace[668360425] transaction","detail":"{read_only:false; response_revision:22151; number_of_response:1; }","duration":"242.50318ms","start":"2026-08-27T20:01:04.877824Z","end":"2026-08-27T20:01:05.120327Z","steps":["trace[668360425] 'process raft request' (duration: 82.933124ms)","trace[668360425] 'compare' (duration: 159.071514ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T20:01:05.150855Z","caller":"traceutil/trace.go:171","msg":"trace[1093402991] transaction","detail":"{read_only:false; response_revision:22152; number_of_response:1; }","duration":"130.38684ms","start":"2026-08-27T20:01:05.02045Z","end":"2026-08-27T20:01:05.150837Z","steps":["trace[1093402991] 'process raft request' (duration: 130.229236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:01:05.440707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.255871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-27T20:01:05.441195Z","caller":"traceutil/trace.go:171","msg":"trace[1821094996] transaction","detail":"{read_only:false; response_revision:22153; number_of_response:1; }","duration":"418.572255ms","start":"2026-08-27T20:01:05.02257Z","end":"2026-08-27T20:01:05.441142Z","steps":["trace[1821094996] 'process raft request' (duration: 277.767963ms)","trace[1821094996] 'compare' (duration: 139.86754ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T20:01:05.441435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:01:05.022535Z","time spent":"418.765069ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-27T20:01:05.441451Z","caller":"traceutil/trace.go:171","msg":"trace[723091506] transaction","detail":"{read_only:false; response_revision:22154; number_of_response:1; }","duration":"350.736185ms","start":"2026-08-27T20:01:05.090635Z","end":"2026-08-27T20:01:05.441371Z","steps":["trace[723091506] 'process raft request' (duration: 350.403927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:01:05.44189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:01:05.090592Z","time spent":"351.105944ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T20:01:05.547065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.859998ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-27T20:01:05.54715Z","caller":"traceutil/trace.go:171","msg":"trace[931535133] linearizableReadLoop","detail":"{readStateIndex:22912; appliedIndex:22909; }","duration":"122.767167ms","start":"2026-08-27T20:01:05.424364Z","end":"2026-08-27T20:01:05.547131Z","steps":["trace[931535133] 'read index received' (duration: 13.183508ms)","trace[931535133] 'applied index is now lower than readState.Index' (duration: 109.582239ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T20:01:05.547423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.058803ms","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-27T20:01:05.547519Z","caller":"traceutil/trace.go:171","msg":"trace[1017560989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22154; }","duration":"123.184677ms","start":"2026-08-27T20:01:05.424294Z","end":"2026-08-27T20:01:05.547479Z","steps":["trace[1017560989] 'agreement among raft nodes before linearized reading' (duration: 122.931781ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:01:05.945174Z","caller":"traceutil/trace.go:171","msg":"trace[1478314896] linearizableReadLoop","detail":"{readStateIndex:22913; appliedIndex:22912; }","duration":"312.469891ms","start":"2026-08-27T20:01:05.632676Z","end":"2026-08-27T20:01:05.945146Z","steps":["trace[1478314896] 'read index received' (duration: 312.016851ms)","trace[1478314896] 'applied index is now lower than readState.Index' (duration: 451.62µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T20:01:05.945303Z","caller":"traceutil/trace.go:171","msg":"trace[1943598243] transaction","detail":"{read_only:false; response_revision:22155; number_of_response:1; }","duration":"389.41507ms","start":"2026-08-27T20:01:05.555857Z","end":"2026-08-27T20:01:05.945272Z","steps":["trace[1943598243] 'process raft request' (duration: 389.104673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:01:05.9454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.922504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-27T20:01:05.945402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.726897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T20:01:05.945444Z","caller":"traceutil/trace.go:171","msg":"trace[1218480135] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:22155; }","duration":"280.040498ms","start":"2026-08-27T20:01:05.66539Z","end":"2026-08-27T20:01:05.945431Z","steps":["trace[1218480135] 'agreement among raft nodes before linearized reading' (duration: 279.895224ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:01:05.945469Z","caller":"traceutil/trace.go:171","msg":"trace[1010141980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22155; }","duration":"312.85223ms","start":"2026-08-27T20:01:05.632597Z","end":"2026-08-27T20:01:05.945449Z","steps":["trace[1010141980] 'agreement among raft nodes before linearized reading' (duration: 312.668006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:01:05.945617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:01:05.632568Z","time spent":"313.031035ms","remote":"127.0.0.1:58838","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-27T20:01:05.94596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:01:05.555823Z","time spent":"389.559793ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-27T20:04:46.574732Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21636} {"level":"info","ts":"2026-08-27T20:04:46.707159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21636,"took":"126.344789ms","hash":487194421,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":30658560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-27T20:04:46.707249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":487194421,"revision":21636,"compact-revision":19392} {"level":"warn","ts":"2026-08-27T20:05:20.247632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.372968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-27T20:05:20.247749Z","caller":"traceutil/trace.go:171","msg":"trace[862194567] linearizableReadLoop","detail":"{readStateIndex:25573; appliedIndex:25572; }","duration":"282.038211ms","start":"2026-08-27T20:05:19.965692Z","end":"2026-08-27T20:05:20.24773Z","steps":["trace[862194567] 'read index received' (duration: 4.326286ms)","trace[862194567] 'applied index is now lower than readState.Index' (duration: 277.710645ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T20:05:20.247861Z","caller":"traceutil/trace.go:171","msg":"trace[1157389176] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"317.82123ms","start":"2026-08-27T20:05:19.930005Z","end":"2026-08-27T20:05:20.247826Z","steps":["trace[1157389176] 'process raft request' (duration: 40.122725ms)","trace[1157389176] 'compare' (duration: 277.213943ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T20:05:20.248071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:05:19.929979Z","time spent":"317.974724ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T20:05:20.248125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.438511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T20:05:20.248257Z","caller":"traceutil/trace.go:171","msg":"trace[789694819] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:24737; }","duration":"282.632605ms","start":"2026-08-27T20:05:19.965609Z","end":"2026-08-27T20:05:20.248241Z","steps":["trace[789694819] 'agreement among raft nodes before linearized reading' (duration: 282.460001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:05:20.297479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.77193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-27T20:05:20.297588Z","caller":"traceutil/trace.go:171","msg":"trace[592236847] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24739; }","duration":"133.934664ms","start":"2026-08-27T20:05:20.163635Z","end":"2026-08-27T20:05:20.29757Z","steps":["trace[592236847] 'agreement among raft nodes before linearized reading' (duration: 133.703899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:05:20.297853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.2364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-27T20:05:20.297921Z","caller":"traceutil/trace.go:171","msg":"trace[776309419] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:24739; }","duration":"311.323471ms","start":"2026-08-27T20:05:19.986587Z","end":"2026-08-27T20:05:20.29791Z","steps":["trace[776309419] 'agreement among raft nodes before linearized reading' (duration: 311.189788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:05:20.297952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:05:19.986577Z","time spent":"311.365993ms","remote":"127.0.0.1:58864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"warn","ts":"2026-08-27T20:05:20.298283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.560717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager\" ","response":"range_response_count:1 size:5167"} {"level":"info","ts":"2026-08-27T20:05:20.29833Z","caller":"traceutil/trace.go:171","msg":"trace[394807788] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager; range_end:; response_count:1; response_revision:24739; }","duration":"313.605668ms","start":"2026-08-27T20:05:19.984708Z","end":"2026-08-27T20:05:20.298314Z","steps":["trace[394807788] 'agreement among raft nodes before linearized reading' (duration: 313.468954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:05:20.298363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:05:19.984672Z","time spent":"313.68417ms","remote":"127.0.0.1:59314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":5189,"request content":"key:\"/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager\" "} {"level":"info","ts":"2026-08-27T20:08:42.151157Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b0a.wal"} {"level":"info","ts":"2026-08-27T20:09:46.584364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23878} {"level":"info","ts":"2026-08-27T20:09:46.712744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23878,"took":"123.867587ms","hash":1566392892,"current-db-size-bytes":58699776,"current-db-size":"59 MB","current-db-size-in-use-bytes":42127360,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-27T20:09:46.712822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1566392892,"revision":23878,"compact-revision":21636} {"level":"info","ts":"2026-08-27T20:14:24.359067Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"14f8f2dfdaccebdb","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-27T20:14:24.366734Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-27T20:14:24.366863Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-27T20:14:46.59162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26988} {"level":"info","ts":"2026-08-27T20:14:46.755983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26988,"took":"156.500228ms","hash":2497181475,"current-db-size-bytes":58699776,"current-db-size":"59 MB","current-db-size-in-use-bytes":30171136,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-27T20:14:46.756054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2497181475,"revision":26988,"compact-revision":23878}