{"level":"warn","ts":"2026-08-04T15:20:27.509263Z","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-04T15:20:27.509406Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-04T15:20:27.509508Z","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-04T15:20:27.509527Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-08-04T15:20:27.509551Z","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-04T15:20:27.510481Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-08-04T15:20:27.510629Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-04T15:20:27.513161Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.14584ms"} {"level":"info","ts":"2026-08-04T15:20:27.523138Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-08-04T15:20:27.523231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T15:20:27.523269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-08-04T15:20:27.523282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T15:20:27.523295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-08-04T15:20:27.523332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-08-04T15:20:27.527686Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T15:20:27.529143Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T15:20:27.53059Z","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-04T15:20:27.533494Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T15:20:27.533813Z","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-04T15:20:27.533883Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T15:20:27.533956Z","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-04T15:20:27.533972Z","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-04T15:20:27.535204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-08-04T15:20:27.535355Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-08-04T15:20:27.539404Z","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-04T15:20:27.539734Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-08-04T15:20:27.539803Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-08-04T15:20:27.539918Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T15:20:27.539701Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T15:20:27.924226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T15:20:27.924282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T15:20:27.924323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-08-04T15:20:27.924342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-08-04T15:20:27.924353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-08-04T15:20:27.92437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-08-04T15:20:27.924389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-08-04T15:20:27.925311Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T15:20:27.925322Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:20:27.925584Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T15:20:27.925615Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T15:20:27.925322Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:20:27.925406Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:20:27.92642Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:20:27.926538Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:20:27.926589Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:20:27.929133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-08-04T15:20:27.929648Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-04T15:20:42.08541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.337863ms","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-04T15:20:42.085565Z","caller":"traceutil/trace.go:171","msg":"trace[910559124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:359; }","duration":"154.624568ms","start":"2026-08-04T15:20:41.930906Z","end":"2026-08-04T15:20:42.08553Z","steps":["trace[910559124] 'range keys from in-memory index tree' (duration: 154.202851ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:42.193938Z","caller":"traceutil/trace.go:171","msg":"trace[633450581] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"103.014744ms","start":"2026-08-04T15:20:42.090898Z","end":"2026-08-04T15:20:42.193912Z","steps":["trace[633450581] 'process raft request' (duration: 102.864821ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:05.374621Z","caller":"traceutil/trace.go:171","msg":"trace[542817843] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"298.943471ms","start":"2026-08-04T15:21:05.07563Z","end":"2026-08-04T15:21:05.374574Z","steps":["trace[542817843] 'process raft request' (duration: 199.26382ms)","trace[542817843] 'compare' (duration: 99.371135ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:05.376736Z","caller":"traceutil/trace.go:171","msg":"trace[248817210] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:535; }","duration":"130.611761ms","start":"2026-08-04T15:21:05.246058Z","end":"2026-08-04T15:21:05.37667Z","steps":["trace[248817210] 'read index received' (duration: 28.84896ms)","trace[248817210] 'applied index is now lower than readState.Index' (duration: 101.761151ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:05.376897Z","caller":"traceutil/trace.go:171","msg":"trace[1631896126] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"185.151941ms","start":"2026-08-04T15:21:05.191729Z","end":"2026-08-04T15:21:05.376881Z","steps":["trace[1631896126] 'process raft request' (duration: 184.873426ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:05.376885Z","caller":"traceutil/trace.go:171","msg":"trace[1124092371] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"259.836255ms","start":"2026-08-04T15:21:05.117012Z","end":"2026-08-04T15:21:05.376848Z","steps":["trace[1124092371] 'process raft request' (duration: 259.455258ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:05.377075Z","caller":"traceutil/trace.go:171","msg":"trace[1389300306] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"260.695301ms","start":"2026-08-04T15:21:05.116364Z","end":"2026-08-04T15:21:05.37706Z","steps":["trace[1389300306] 'process raft request' (duration: 259.887446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:05.377385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.293853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-04T15:21:05.377435Z","caller":"traceutil/trace.go:171","msg":"trace[1538485594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"131.373835ms","start":"2026-08-04T15:21:05.246049Z","end":"2026-08-04T15:21:05.377423Z","steps":["trace[1538485594] 'agreement among raft nodes before linearized reading' (duration: 131.13473ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:12.248002Z","caller":"traceutil/trace.go:171","msg":"trace[1754852383] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"244.025265ms","start":"2026-08-04T15:21:12.003951Z","end":"2026-08-04T15:21:12.247976Z","steps":["trace[1754852383] 'process raft request' (duration: 242.856063ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:12.437269Z","caller":"traceutil/trace.go:171","msg":"trace[444620107] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"154.406405ms","start":"2026-08-04T15:21:12.282838Z","end":"2026-08-04T15:21:12.437244Z","steps":["trace[444620107] 'process raft request' (duration: 154.171341ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:12.448757Z","caller":"traceutil/trace.go:171","msg":"trace[385772284] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"111.257162ms","start":"2026-08-04T15:21:12.337481Z","end":"2026-08-04T15:21:12.448738Z","steps":["trace[385772284] 'read index received' (duration: 99.543009ms)","trace[385772284] 'applied index is now lower than readState.Index' (duration: 11.713353ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:12.448826Z","caller":"traceutil/trace.go:171","msg":"trace[1294065474] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"127.616614ms","start":"2026-08-04T15:21:12.321202Z","end":"2026-08-04T15:21:12.448818Z","steps":["trace[1294065474] 'process raft request' (duration: 127.472271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:12.449008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.520547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:21:12.449055Z","caller":"traceutil/trace.go:171","msg":"trace[2058946708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"111.605868ms","start":"2026-08-04T15:21:12.337441Z","end":"2026-08-04T15:21:12.449047Z","steps":["trace[2058946708] 'agreement among raft nodes before linearized reading' (duration: 111.427615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:12.449068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.512885ms","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-04T15:21:12.449193Z","caller":"traceutil/trace.go:171","msg":"trace[499791860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"102.662277ms","start":"2026-08-04T15:21:12.34651Z","end":"2026-08-04T15:21:12.449172Z","steps":["trace[499791860] 'agreement among raft nodes before linearized reading' (duration: 102.386922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:12.930699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.809029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:21:12.930781Z","caller":"traceutil/trace.go:171","msg":"trace[325039429] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"383.437193ms","start":"2026-08-04T15:21:12.547332Z","end":"2026-08-04T15:21:12.930769Z","steps":["trace[325039429] 'read index received' (duration: 202.103045ms)","trace[325039429] 'applied index is now lower than readState.Index' (duration: 181.333028ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:12.930912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.575465ms","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-04T15:21:12.930934Z","caller":"traceutil/trace.go:171","msg":"trace[1403076848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"383.622836ms","start":"2026-08-04T15:21:12.547305Z","end":"2026-08-04T15:21:12.930928Z","steps":["trace[1403076848] 'agreement among raft nodes before linearized reading' (duration: 383.496943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:12.930957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:12.547295Z","time spent":"383.657086ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-04T15:21:12.931116Z","caller":"traceutil/trace.go:171","msg":"trace[469937498] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"445.70915ms","start":"2026-08-04T15:21:12.485398Z","end":"2026-08-04T15:21:12.931107Z","steps":["trace[469937498] 'process raft request' (duration: 264.115707ms)","trace[469937498] 'compare' (duration: 180.577344ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:12.931155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:12.485385Z","time spent":"445.753281ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:21:28.159909Z","caller":"traceutil/trace.go:171","msg":"trace[536273305] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"100.586708ms","start":"2026-08-04T15:21:28.059292Z","end":"2026-08-04T15:21:28.159878Z","steps":["trace[536273305] 'process raft request' (duration: 100.385994ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:22:39.639246Z","caller":"traceutil/trace.go:171","msg":"trace[965091181] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"193.941676ms","start":"2026-08-04T15:22:39.445253Z","end":"2026-08-04T15:22:39.639195Z","steps":["trace[965091181] 'process raft request' (duration: 193.469818ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:22:53.35215Z","caller":"traceutil/trace.go:171","msg":"trace[1801934850] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"118.195672ms","start":"2026-08-04T15:22:53.233913Z","end":"2026-08-04T15:22:53.352108Z","steps":["trace[1801934850] 'process raft request' (duration: 117.547259ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:25.292445Z","caller":"traceutil/trace.go:171","msg":"trace[631839493] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"119.772089ms","start":"2026-08-04T15:23:25.172647Z","end":"2026-08-04T15:23:25.292419Z","steps":["trace[631839493] 'process raft request' (duration: 119.597476ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:25.382315Z","caller":"traceutil/trace.go:171","msg":"trace[1604409212] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"113.329572ms","start":"2026-08-04T15:23:25.268959Z","end":"2026-08-04T15:23:25.382288Z","steps":["trace[1604409212] 'process raft request' (duration: 112.64112ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:53.319283Z","caller":"traceutil/trace.go:171","msg":"trace[1390673910] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"113.029633ms","start":"2026-08-04T15:24:53.206214Z","end":"2026-08-04T15:24:53.319244Z","steps":["trace[1390673910] 'process raft request' (duration: 112.815438ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:53.53661Z","caller":"traceutil/trace.go:171","msg":"trace[1136268923] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"102.636246ms","start":"2026-08-04T15:24:53.433946Z","end":"2026-08-04T15:24:53.536582Z","steps":["trace[1136268923] 'process raft request' (duration: 102.475683ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:47.390383Z","caller":"traceutil/trace.go:171","msg":"trace[273653759] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"201.752135ms","start":"2026-08-04T15:28:47.188613Z","end":"2026-08-04T15:28:47.390365Z","steps":["trace[273653759] 'process raft request' (duration: 141.066772ms)","trace[273653759] 'compare' (duration: 60.306217ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:47.390383Z","caller":"traceutil/trace.go:171","msg":"trace[2127649089] linearizableReadLoop","detail":"{readStateIndex:5142; appliedIndex:5141; }","duration":"200.682795ms","start":"2026-08-04T15:28:47.189681Z","end":"2026-08-04T15:28:47.390363Z","steps":["trace[2127649089] 'read index received' (duration: 139.999412ms)","trace[2127649089] 'applied index is now lower than readState.Index' (duration: 60.682763ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:28:47.39054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.850968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62874"} {"level":"info","ts":"2026-08-04T15:28:47.390572Z","caller":"traceutil/trace.go:171","msg":"trace[1351784093] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4956; }","duration":"200.90042ms","start":"2026-08-04T15:28:47.189666Z","end":"2026-08-04T15:28:47.390566Z","steps":["trace[1351784093] 'agreement among raft nodes before linearized reading' (duration: 200.720646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:47.390954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.07625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-636b866c-ffc8-4e3f-a952-e1531692ccef\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-04T15:28:47.39103Z","caller":"traceutil/trace.go:171","msg":"trace[1855332486] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-636b866c-ffc8-4e3f-a952-e1531692ccef; range_end:; response_count:1; response_revision:4956; }","duration":"169.216903ms","start":"2026-08-04T15:28:47.221783Z","end":"2026-08-04T15:28:47.391Z","steps":["trace[1855332486] 'agreement among raft nodes before linearized reading' (duration: 168.876887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:47.857378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.942673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-08-04T15:28:47.857433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.632519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-636b866c-ffc8-4e3f-a952-e1531692ccef\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-04T15:28:47.857569Z","caller":"traceutil/trace.go:171","msg":"trace[248980234] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-636b866c-ffc8-4e3f-a952-e1531692ccef; range_end:; response_count:1; response_revision:4958; }","duration":"251.810982ms","start":"2026-08-04T15:28:47.605738Z","end":"2026-08-04T15:28:47.857549Z","steps":["trace[248980234] 'range keys from in-memory index tree' (duration: 251.236482ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:47.857593Z","caller":"traceutil/trace.go:171","msg":"trace[1859290168] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"251.310042ms","start":"2026-08-04T15:28:47.606243Z","end":"2026-08-04T15:28:47.857553Z","steps":["trace[1859290168] 'process raft request' (duration: 39.120124ms)","trace[1859290168] 'compare' (duration: 211.804621ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:47.972116Z","caller":"traceutil/trace.go:171","msg":"trace[1914504377] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"109.026138ms","start":"2026-08-04T15:28:47.863064Z","end":"2026-08-04T15:28:47.97209Z","steps":["trace[1914504377] 'process raft request' (duration: 108.494138ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:47.97212Z","caller":"traceutil/trace.go:171","msg":"trace[719271201] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"104.388613ms","start":"2026-08-04T15:28:47.867707Z","end":"2026-08-04T15:28:47.972096Z","steps":["trace[719271201] 'process raft request' (duration: 104.323041ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:49.918213Z","caller":"traceutil/trace.go:171","msg":"trace[1338660237] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5193; }","duration":"182.086171ms","start":"2026-08-04T15:28:49.736098Z","end":"2026-08-04T15:28:49.918184Z","steps":["trace[1338660237] 'read index received' (duration: 21.835634ms)","trace[1338660237] 'applied index is now lower than readState.Index' (duration: 160.249047ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:49.918277Z","caller":"traceutil/trace.go:171","msg":"trace[1144232056] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"227.022932ms","start":"2026-08-04T15:28:49.691231Z","end":"2026-08-04T15:28:49.918254Z","steps":["trace[1144232056] 'process raft request' (duration: 226.776958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:49.91845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.333005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-04T15:28:49.918515Z","caller":"traceutil/trace.go:171","msg":"trace[1005345956] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5008; }","duration":"182.445807ms","start":"2026-08-04T15:28:49.736052Z","end":"2026-08-04T15:28:49.918498Z","steps":["trace[1005345956] 'agreement among raft nodes before linearized reading' (duration: 182.244883ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:50.090294Z","caller":"traceutil/trace.go:171","msg":"trace[1680634709] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"165.619797ms","start":"2026-08-04T15:28:49.92465Z","end":"2026-08-04T15:28:50.09027Z","steps":["trace[1680634709] 'process raft request' (duration: 159.149037ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:50.090642Z","caller":"traceutil/trace.go:171","msg":"trace[1272831349] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"165.793558ms","start":"2026-08-04T15:28:49.924831Z","end":"2026-08-04T15:28:50.090624Z","steps":["trace[1272831349] 'process raft request' (duration: 165.597885ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:50.090805Z","caller":"traceutil/trace.go:171","msg":"trace[876000419] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"166.126985ms","start":"2026-08-04T15:28:49.924668Z","end":"2026-08-04T15:28:50.090795Z","steps":["trace[876000419] 'process raft request' (duration: 165.534645ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:50.091241Z","caller":"traceutil/trace.go:171","msg":"trace[973818569] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"165.764478ms","start":"2026-08-04T15:28:49.925454Z","end":"2026-08-04T15:28:50.091219Z","steps":["trace[973818569] 'process raft request' (duration: 165.100716ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:50.090805Z","caller":"traceutil/trace.go:171","msg":"trace[1196043950] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5196; }","duration":"144.186129ms","start":"2026-08-04T15:28:49.946442Z","end":"2026-08-04T15:28:50.090628Z","steps":["trace[1196043950] 'read index received' (duration: 137.286772ms)","trace[1196043950] 'applied index is now lower than readState.Index' (duration: 6.898247ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:28:50.091629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.163548ms","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-04T15:28:50.091693Z","caller":"traceutil/trace.go:171","msg":"trace[480699890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5013; }","duration":"145.264739ms","start":"2026-08-04T15:28:49.946415Z","end":"2026-08-04T15:28:50.091679Z","steps":["trace[480699890] 'agreement among raft nodes before linearized reading' (duration: 145.070456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:50.091821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.197441ms","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-04T15:28:50.09189Z","caller":"traceutil/trace.go:171","msg":"trace[318660542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5013; }","duration":"116.325934ms","start":"2026-08-04T15:28:49.975548Z","end":"2026-08-04T15:28:50.091874Z","steps":["trace[318660542] 'agreement among raft nodes before linearized reading' (duration: 116.10169ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:59.663384Z","caller":"traceutil/trace.go:171","msg":"trace[1941519522] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"133.780517ms","start":"2026-08-04T15:28:59.529558Z","end":"2026-08-04T15:28:59.663338Z","steps":["trace[1941519522] 'process raft request' (duration: 93.174265ms)","trace[1941519522] 'compare' (duration: 40.273216ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:29:07.927877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.846106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5848e8b2-ff4c-4d4c-9bd3-12f267393f14\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-08-04T15:29:07.927914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.209102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5848e8b2-ff4c-4d4c-9bd3-12f267393f14\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-04T15:29:07.927971Z","caller":"traceutil/trace.go:171","msg":"trace[1321895902] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5848e8b2-ff4c-4d4c-9bd3-12f267393f14; range_end:; response_count:1; response_revision:5362; }","duration":"129.013969ms","start":"2026-08-04T15:29:07.798936Z","end":"2026-08-04T15:29:07.92795Z","steps":["trace[1321895902] 'range keys from in-memory index tree' (duration: 128.603152ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:29:07.927994Z","caller":"traceutil/trace.go:171","msg":"trace[1270314134] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5848e8b2-ff4c-4d4c-9bd3-12f267393f14; range_end:; response_count:1; response_revision:5362; }","duration":"107.339235ms","start":"2026-08-04T15:29:07.820634Z","end":"2026-08-04T15:29:07.927973Z","steps":["trace[1270314134] 'range keys from in-memory index tree' (duration: 107.024329ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:29:08.268179Z","caller":"traceutil/trace.go:171","msg":"trace[1466182637] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"225.905834ms","start":"2026-08-04T15:29:08.042242Z","end":"2026-08-04T15:29:08.268148Z","steps":["trace[1466182637] 'process raft request' (duration: 225.708701ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:30:28.074165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2966} {"level":"info","ts":"2026-08-04T15:30:28.150203Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2966,"took":"74.214386ms","hash":4089997338,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":34209792,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-04T15:30:28.150268Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4089997338,"revision":2966,"compact-revision":-1} {"level":"info","ts":"2026-08-04T15:34:59.946931Z","caller":"traceutil/trace.go:171","msg":"trace[2080187881] transaction","detail":"{read_only:false; response_revision:8119; number_of_response:1; }","duration":"176.726476ms","start":"2026-08-04T15:34:59.770185Z","end":"2026-08-04T15:34:59.946911Z","steps":["trace[2080187881] 'process raft request' (duration: 176.584794ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:34:59.956213Z","caller":"traceutil/trace.go:171","msg":"trace[673082433] transaction","detail":"{read_only:false; response_revision:8121; number_of_response:1; }","duration":"115.619739ms","start":"2026-08-04T15:34:59.84057Z","end":"2026-08-04T15:34:59.95619Z","steps":["trace[673082433] 'process raft request' (duration: 115.577798ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:34:59.956283Z","caller":"traceutil/trace.go:171","msg":"trace[578836469] transaction","detail":"{read_only:false; response_revision:8120; number_of_response:1; }","duration":"120.488593ms","start":"2026-08-04T15:34:59.835748Z","end":"2026-08-04T15:34:59.956237Z","steps":["trace[578836469] 'process raft request' (duration: 120.214827ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:35:10.946662Z","caller":"traceutil/trace.go:171","msg":"trace[2136128310] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"123.651153ms","start":"2026-08-04T15:35:10.822982Z","end":"2026-08-04T15:35:10.946633Z","steps":["trace[2136128310] 'process raft request' (duration: 123.464759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:35:11.682707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.195255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zcr6g\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-04T15:35:11.682772Z","caller":"traceutil/trace.go:171","msg":"trace[474375974] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zcr6g; range_end:; response_count:1; response_revision:8181; }","duration":"111.300097ms","start":"2026-08-04T15:35:11.571456Z","end":"2026-08-04T15:35:11.682756Z","steps":["trace[474375974] 'range keys from in-memory index tree' (duration: 111.021422ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:35:12.420352Z","caller":"traceutil/trace.go:171","msg":"trace[1326058104] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"175.7979ms","start":"2026-08-04T15:35:12.244517Z","end":"2026-08-04T15:35:12.420315Z","steps":["trace[1326058104] 'process raft request' (duration: 175.436173ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:35:28.08004Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-08-04T15:35:28.174368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"92.691911ms","hash":1543399054,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":23044096,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-04T15:35:28.17445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1543399054,"revision":6103,"compact-revision":2966} {"level":"info","ts":"2026-08-04T15:35:38.761862Z","caller":"traceutil/trace.go:171","msg":"trace[173252468] transaction","detail":"{read_only:false; response_revision:8380; number_of_response:1; }","duration":"205.648997ms","start":"2026-08-04T15:35:38.556196Z","end":"2026-08-04T15:35:38.761845Z","steps":["trace[173252468] 'process raft request' (duration: 205.515234ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:35:38.762063Z","caller":"traceutil/trace.go:171","msg":"trace[15968889] linearizableReadLoop","detail":"{readStateIndex:8677; appliedIndex:8675; }","duration":"182.635608ms","start":"2026-08-04T15:35:38.579402Z","end":"2026-08-04T15:35:38.762038Z","steps":["trace[15968889] 'read index received' (duration: 182.334933ms)","trace[15968889] 'applied index is now lower than readState.Index' (duration: 299.265µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:35:38.762201Z","caller":"traceutil/trace.go:171","msg":"trace[1561746774] transaction","detail":"{read_only:false; response_revision:8381; number_of_response:1; }","duration":"205.970142ms","start":"2026-08-04T15:35:38.55622Z","end":"2026-08-04T15:35:38.76219Z","steps":["trace[1561746774] 'process raft request' (duration: 205.592225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:35:38.762252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.839982ms","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-04T15:35:38.762278Z","caller":"traceutil/trace.go:171","msg":"trace[1289047311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8381; }","duration":"182.913384ms","start":"2026-08-04T15:35:38.579358Z","end":"2026-08-04T15:35:38.762271Z","steps":["trace[1289047311] 'agreement among raft nodes before linearized reading' (duration: 182.828082ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:35:38.908431Z","caller":"traceutil/trace.go:171","msg":"trace[493149495] linearizableReadLoop","detail":"{readStateIndex:8678; appliedIndex:8677; }","duration":"146.223798ms","start":"2026-08-04T15:35:38.762156Z","end":"2026-08-04T15:35:38.908379Z","steps":["trace[493149495] 'read index received' (duration: 144.342271ms)","trace[493149495] 'applied index is now lower than readState.Index' (duration: 1.880237ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:35:38.908716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.266472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-04T15:35:38.908775Z","caller":"traceutil/trace.go:171","msg":"trace[802293540] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8382; }","duration":"311.401645ms","start":"2026-08-04T15:35:38.597356Z","end":"2026-08-04T15:35:38.908757Z","steps":["trace[802293540] 'agreement among raft nodes before linearized reading' (duration: 311.15219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:35:38.908825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:35:38.597336Z","time spent":"311.475926ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-04T15:35:38.909285Z","caller":"traceutil/trace.go:171","msg":"trace[2075367078] transaction","detail":"{read_only:false; response_revision:8382; number_of_response:1; }","duration":"302.836482ms","start":"2026-08-04T15:35:38.606429Z","end":"2026-08-04T15:35:38.909265Z","steps":["trace[2075367078] 'process raft request' (duration: 300.09333ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:35:38.90957Z","caller":"traceutil/trace.go:171","msg":"trace[2138330338] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"143.325133ms","start":"2026-08-04T15:35:38.766229Z","end":"2026-08-04T15:35:38.909554Z","steps":["trace[2138330338] 'process raft request' (duration: 143.218571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:35:38.909702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:35:38.606407Z","time spent":"302.963535ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:35:40.238059Z","caller":"traceutil/trace.go:171","msg":"trace[1413709514] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"139.059881ms","start":"2026-08-04T15:35:40.098974Z","end":"2026-08-04T15:35:40.238034Z","steps":["trace[1413709514] 'process raft request' (duration: 138.868198ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:35:40.238121Z","caller":"traceutil/trace.go:171","msg":"trace[1471824906] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"135.015315ms","start":"2026-08-04T15:35:40.103078Z","end":"2026-08-04T15:35:40.238093Z","steps":["trace[1471824906] 'process raft request' (duration: 134.899152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:35:50.928561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.011167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-04T15:35:50.928648Z","caller":"traceutil/trace.go:171","msg":"trace[1303987827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8486; }","duration":"113.149819ms","start":"2026-08-04T15:35:50.815481Z","end":"2026-08-04T15:35:50.928631Z","steps":["trace[1303987827] 'range keys from in-memory index tree' (duration: 112.796273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:35:50.928653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.136563ms","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-04T15:35:50.928766Z","caller":"traceutil/trace.go:171","msg":"trace[308136793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8486; }","duration":"110.298845ms","start":"2026-08-04T15:35:50.818443Z","end":"2026-08-04T15:35:50.928742Z","steps":["trace[308136793] 'range keys from in-memory index tree' (duration: 109.925949ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:35:52.314857Z","caller":"traceutil/trace.go:171","msg":"trace[1796829442] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"118.96698ms","start":"2026-08-04T15:35:52.195865Z","end":"2026-08-04T15:35:52.314831Z","steps":["trace[1796829442] 'process raft request' (duration: 118.756956ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:36:40.182699Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244c.wal"} {"level":"info","ts":"2026-08-04T15:38:01.213075Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-04T15:38:01.220862Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-04T15:38:01.221019Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-04T15:40:28.08575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8277} {"level":"info","ts":"2026-08-04T15:40:28.165276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8277,"took":"77.269493ms","hash":2757305047,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":27017216,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-04T15:40:28.165345Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2757305047,"revision":8277,"compact-revision":6103} {"level":"info","ts":"2026-08-04T15:40:56.612105Z","caller":"traceutil/trace.go:171","msg":"trace[1712076714] linearizableReadLoop","detail":"{readStateIndex:11528; appliedIndex:11526; }","duration":"146.136453ms","start":"2026-08-04T15:40:56.46594Z","end":"2026-08-04T15:40:56.612076Z","steps":["trace[1712076714] 'read index received' (duration: 122.017971ms)","trace[1712076714] 'applied index is now lower than readState.Index' (duration: 24.116902ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:40:56.61222Z","caller":"traceutil/trace.go:171","msg":"trace[549129408] transaction","detail":"{read_only:false; response_revision:11150; number_of_response:1; }","duration":"153.164468ms","start":"2026-08-04T15:40:56.459015Z","end":"2026-08-04T15:40:56.61218Z","steps":["trace[549129408] 'process raft request' (duration: 152.894553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:40:56.612364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.411639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-04T15:40:56.612415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.473905ms","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-04T15:40:56.612447Z","caller":"traceutil/trace.go:171","msg":"trace[221983594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11150; }","duration":"146.539522ms","start":"2026-08-04T15:40:56.465889Z","end":"2026-08-04T15:40:56.612429Z","steps":["trace[221983594] 'agreement among raft nodes before linearized reading' (duration: 146.330158ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:40:56.612469Z","caller":"traceutil/trace.go:171","msg":"trace[1929226981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11150; }","duration":"140.583897ms","start":"2026-08-04T15:40:56.47187Z","end":"2026-08-04T15:40:56.612454Z","steps":["trace[1929226981] 'agreement among raft nodes before linearized reading' (duration: 140.391464ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:45:28.091046Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10996} {"level":"info","ts":"2026-08-04T15:45:28.172951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10996,"took":"79.542149ms","hash":2678009524,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":28372992,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-04T15:45:28.173003Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2678009524,"revision":10996,"compact-revision":8277} {"level":"info","ts":"2026-08-04T15:46:26.501499Z","caller":"traceutil/trace.go:171","msg":"trace[1914909432] linearizableReadLoop","detail":"{readStateIndex:13894; appliedIndex:13893; }","duration":"111.506097ms","start":"2026-08-04T15:46:26.389964Z","end":"2026-08-04T15:46:26.50147Z","steps":["trace[1914909432] 'read index received' (duration: 108.00325ms)","trace[1914909432] 'applied index is now lower than readState.Index' (duration: 3.501117ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:46:26.501657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.68344ms","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-04T15:46:26.501701Z","caller":"traceutil/trace.go:171","msg":"trace[764956390] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13437; }","duration":"111.778512ms","start":"2026-08-04T15:46:26.38991Z","end":"2026-08-04T15:46:26.501689Z","steps":["trace[764956390] 'agreement among raft nodes before linearized reading' (duration: 111.68141ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:47:16.697336Z","caller":"traceutil/trace.go:171","msg":"trace[739585247] transaction","detail":"{read_only:false; response_revision:13714; number_of_response:1; }","duration":"122.551918ms","start":"2026-08-04T15:47:16.574753Z","end":"2026-08-04T15:47:16.697305Z","steps":["trace[739585247] 'process raft request' (duration: 109.289444ms)","trace[739585247] 'compare' (duration: 13.04422ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:47:16.697573Z","caller":"traceutil/trace.go:171","msg":"trace[542048102] transaction","detail":"{read_only:false; response_revision:13715; number_of_response:1; }","duration":"115.216087ms","start":"2026-08-04T15:47:16.582317Z","end":"2026-08-04T15:47:16.697533Z","steps":["trace[542048102] 'process raft request' (duration: 114.918982ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:47:17.082964Z","caller":"traceutil/trace.go:171","msg":"trace[1915424907] linearizableReadLoop","detail":"{readStateIndex:14185; appliedIndex:14184; }","duration":"139.226045ms","start":"2026-08-04T15:47:16.943701Z","end":"2026-08-04T15:47:17.082927Z","steps":["trace[1915424907] 'read index received' (duration: 139.080643ms)","trace[1915424907] 'applied index is now lower than readState.Index' (duration: 143.682µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:47:17.083106Z","caller":"traceutil/trace.go:171","msg":"trace[1715113447] transaction","detail":"{read_only:false; response_revision:13716; number_of_response:1; }","duration":"143.386555ms","start":"2026-08-04T15:47:16.939708Z","end":"2026-08-04T15:47:17.083095Z","steps":["trace[1715113447] 'process raft request' (duration: 142.945726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:47:17.083947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.217384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:594963"} {"level":"info","ts":"2026-08-04T15:47:17.083985Z","caller":"traceutil/trace.go:171","msg":"trace[1903844557] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:13716; }","duration":"140.298607ms","start":"2026-08-04T15:47:16.943679Z","end":"2026-08-04T15:47:17.083977Z","steps":["trace[1903844557] 'agreement among raft nodes before linearized reading' (duration: 139.595044ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:47:17.091865Z","caller":"traceutil/trace.go:171","msg":"trace[847408559] transaction","detail":"{read_only:false; response_revision:13717; number_of_response:1; }","duration":"145.056878ms","start":"2026-08-04T15:47:16.946772Z","end":"2026-08-04T15:47:17.091829Z","steps":["trace[847408559] 'process raft request' (duration: 144.657779ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:47:17.09195Z","caller":"traceutil/trace.go:171","msg":"trace[2046925118] transaction","detail":"{read_only:false; response_revision:13718; number_of_response:1; }","duration":"143.874465ms","start":"2026-08-04T15:47:16.948055Z","end":"2026-08-04T15:47:17.09193Z","steps":["trace[2046925118] 'process raft request' (duration: 143.516828ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:47:17.254053Z","caller":"traceutil/trace.go:171","msg":"trace[22851635] transaction","detail":"{read_only:false; response_revision:13719; number_of_response:1; }","duration":"159.622325ms","start":"2026-08-04T15:47:17.094412Z","end":"2026-08-04T15:47:17.254035Z","steps":["trace[22851635] 'process raft request' (duration: 159.475971ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:48:50.537356Z","caller":"traceutil/trace.go:171","msg":"trace[1307699866] transaction","detail":"{read_only:false; response_revision:14750; number_of_response:1; }","duration":"169.417911ms","start":"2026-08-04T15:48:50.367916Z","end":"2026-08-04T15:48:50.537334Z","steps":["trace[1307699866] 'process raft request' (duration: 169.225448ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:49:10.401174Z","caller":"traceutil/trace.go:171","msg":"trace[108833064] linearizableReadLoop","detail":"{readStateIndex:15364; appliedIndex:15363; }","duration":"300.513072ms","start":"2026-08-04T15:49:10.100638Z","end":"2026-08-04T15:49:10.401151Z","steps":["trace[108833064] 'read index received' (duration: 300.285068ms)","trace[108833064] 'applied index is now lower than readState.Index' (duration: 226.884µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:49:10.401366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.721886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zcr6g\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-04T15:49:10.401401Z","caller":"traceutil/trace.go:171","msg":"trace[675890396] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zcr6g; range_end:; response_count:1; response_revision:14858; }","duration":"300.861069ms","start":"2026-08-04T15:49:10.100531Z","end":"2026-08-04T15:49:10.401392Z","steps":["trace[675890396] 'agreement among raft nodes before linearized reading' (duration: 300.729766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:10.401426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:10.100502Z","time spent":"300.917859ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6552,"request content":"key:\"/registry/pods/openstack/keepalived-zcr6g\" "} {"level":"info","ts":"2026-08-04T15:49:10.401337Z","caller":"traceutil/trace.go:171","msg":"trace[1256131052] transaction","detail":"{read_only:false; response_revision:14858; number_of_response:1; }","duration":"345.323766ms","start":"2026-08-04T15:49:10.055871Z","end":"2026-08-04T15:49:10.401195Z","steps":["trace[1256131052] 'process raft request' (duration: 345.120382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:10.401466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.498732ms","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-04T15:49:10.401503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.302048ms","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-04T15:49:10.401413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.474462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-04T15:49:10.401558Z","caller":"traceutil/trace.go:171","msg":"trace[537684969] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14858; }","duration":"126.628456ms","start":"2026-08-04T15:49:10.27491Z","end":"2026-08-04T15:49:10.401538Z","steps":["trace[537684969] 'agreement among raft nodes before linearized reading' (duration: 126.464512ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:49:10.401565Z","caller":"traceutil/trace.go:171","msg":"trace[841901764] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14858; }","duration":"225.373369ms","start":"2026-08-04T15:49:10.176175Z","end":"2026-08-04T15:49:10.401548Z","steps":["trace[841901764] 'agreement among raft nodes before linearized reading' (duration: 225.311357ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:49:10.401587Z","caller":"traceutil/trace.go:171","msg":"trace[1020579528] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14858; }","duration":"225.693166ms","start":"2026-08-04T15:49:10.175887Z","end":"2026-08-04T15:49:10.401581Z","steps":["trace[1020579528] 'agreement among raft nodes before linearized reading' (duration: 225.42275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:10.401587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.380561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-08-04T15:49:10.401577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.287151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-04T15:49:10.401631Z","caller":"traceutil/trace.go:171","msg":"trace[733277462] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14858; }","duration":"224.431492ms","start":"2026-08-04T15:49:10.177183Z","end":"2026-08-04T15:49:10.401615Z","steps":["trace[733277462] 'agreement among raft nodes before linearized reading' (duration: 224.32164ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:49:10.40166Z","caller":"traceutil/trace.go:171","msg":"trace[1955305444] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:14858; }","duration":"189.407993ms","start":"2026-08-04T15:49:10.212231Z","end":"2026-08-04T15:49:10.401639Z","steps":["trace[1955305444] 'agreement among raft nodes before linearized reading' (duration: 189.221319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:10.40147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.604295ms","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-04T15:49:10.401778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.75681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-04T15:49:10.401816Z","caller":"traceutil/trace.go:171","msg":"trace[1288431750] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14858; }","duration":"124.834291ms","start":"2026-08-04T15:49:10.276971Z","end":"2026-08-04T15:49:10.401805Z","steps":["trace[1288431750] 'agreement among raft nodes before linearized reading' (duration: 124.74795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:10.401776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:10.055852Z","time spent":"345.720205ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:49:10.40186Z","caller":"traceutil/trace.go:171","msg":"trace[56563308] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14858; }","duration":"224.87443ms","start":"2026-08-04T15:49:10.176835Z","end":"2026-08-04T15:49:10.40171Z","steps":["trace[56563308] 'agreement among raft nodes before linearized reading' (duration: 224.570414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:10.401468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.005432ms","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-04T15:49:10.403005Z","caller":"traceutil/trace.go:171","msg":"trace[284745024] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14858; }","duration":"226.570542ms","start":"2026-08-04T15:49:10.17642Z","end":"2026-08-04T15:49:10.40299Z","steps":["trace[284745024] 'agreement among raft nodes before linearized reading' (duration: 225.025262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:10.401919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.412131ms","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-04T15:49:10.403174Z","caller":"traceutil/trace.go:171","msg":"trace[1487968590] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14858; }","duration":"127.677895ms","start":"2026-08-04T15:49:10.275481Z","end":"2026-08-04T15:49:10.403159Z","steps":["trace[1487968590] 'agreement among raft nodes before linearized reading' (duration: 126.416121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:10.401957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.455702ms","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-04T15:49:10.403263Z","caller":"traceutil/trace.go:171","msg":"trace[1644668412] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14858; }","duration":"127.795349ms","start":"2026-08-04T15:49:10.275454Z","end":"2026-08-04T15:49:10.403249Z","steps":["trace[1644668412] 'agreement among raft nodes before linearized reading' (duration: 126.487004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:10.844495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.542674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:49:10.844583Z","caller":"traceutil/trace.go:171","msg":"trace[1049782054] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:14858; }","duration":"104.702467ms","start":"2026-08-04T15:49:10.739861Z","end":"2026-08-04T15:49:10.844563Z","steps":["trace[1049782054] 'count revisions from in-memory index tree' (duration: 104.396272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:10.84536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.214737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:49:10.845428Z","caller":"traceutil/trace.go:171","msg":"trace[1967477228] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:14858; }","duration":"267.327919ms","start":"2026-08-04T15:49:10.578085Z","end":"2026-08-04T15:49:10.845413Z","steps":["trace[1967477228] 'count revisions from in-memory index tree' (duration: 267.085953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:45.927576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186915610468589,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:49:46.268587Z","caller":"traceutil/trace.go:171","msg":"trace[2056969135] transaction","detail":"{read_only:false; response_revision:15063; number_of_response:1; }","duration":"849.454392ms","start":"2026-08-04T15:49:45.419112Z","end":"2026-08-04T15:49:46.268566Z","steps":["trace[2056969135] 'process raft request' (duration: 849.32524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.268708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.419095Z","time spent":"849.543474ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:49:46.275095Z","caller":"traceutil/trace.go:171","msg":"trace[1569679726] linearizableReadLoop","detail":"{readStateIndex:15582; appliedIndex:15576; }","duration":"848.181969ms","start":"2026-08-04T15:49:45.426894Z","end":"2026-08-04T15:49:46.275076Z","steps":["trace[1569679726] 'read index received' (duration: 842.195075ms)","trace[1569679726] 'applied index is now lower than readState.Index' (duration: 5.986314ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:49:46.275331Z","caller":"traceutil/trace.go:171","msg":"trace[961373534] transaction","detail":"{read_only:false; response_revision:15064; number_of_response:1; }","duration":"855.815624ms","start":"2026-08-04T15:49:45.419508Z","end":"2026-08-04T15:49:46.275324Z","steps":["trace[961373534] 'process raft request' (duration: 855.364856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.275405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.419483Z","time spent":"855.869485ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:49:46.275511Z","caller":"traceutil/trace.go:171","msg":"trace[1785516732] transaction","detail":"{read_only:false; response_revision:15065; number_of_response:1; }","duration":"855.673812ms","start":"2026-08-04T15:49:45.419832Z","end":"2026-08-04T15:49:46.275506Z","steps":["trace[1785516732] 'process raft request' (duration: 855.131162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.275544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.419824Z","time spent":"855.701782ms","remote":"127.0.0.1:35640","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-04T15:49:46.275725Z","caller":"traceutil/trace.go:171","msg":"trace[594769621] transaction","detail":"{read_only:false; response_revision:15066; number_of_response:1; }","duration":"787.769887ms","start":"2026-08-04T15:49:45.487949Z","end":"2026-08-04T15:49:46.275719Z","steps":["trace[594769621] 'process raft request' (duration: 787.033532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.275756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.487933Z","time spent":"787.808797ms","remote":"127.0.0.1:35458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":909,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:49:46.275831Z","caller":"traceutil/trace.go:171","msg":"trace[2069692569] transaction","detail":"{read_only:false; response_revision:15067; number_of_response:1; }","duration":"681.379468ms","start":"2026-08-04T15:49:45.594445Z","end":"2026-08-04T15:49:46.275824Z","steps":["trace[2069692569] 'process raft request' (duration: 680.568972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.27586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.594423Z","time spent":"681.421918ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:49:46.275927Z","caller":"traceutil/trace.go:171","msg":"trace[961149886] transaction","detail":"{read_only:false; response_revision:15068; number_of_response:1; }","duration":"647.590002ms","start":"2026-08-04T15:49:45.628332Z","end":"2026-08-04T15:49:46.275922Z","steps":["trace[961149886] 'process raft request' (duration: 646.702275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.275957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.628317Z","time spent":"647.623724ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:49:46.276017Z","caller":"traceutil/trace.go:171","msg":"trace[634312699] transaction","detail":"{read_only:false; response_revision:15069; number_of_response:1; }","duration":"647.311058ms","start":"2026-08-04T15:49:45.628699Z","end":"2026-08-04T15:49:46.27601Z","steps":["trace[634312699] 'process raft request' (duration: 646.353689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.276049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.628672Z","time spent":"647.362248ms","remote":"127.0.0.1:35640","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-04T15:49:46.276183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.29519ms","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-04T15:49:46.276211Z","caller":"traceutil/trace.go:171","msg":"trace[1930790565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15069; }","duration":"849.351592ms","start":"2026-08-04T15:49:45.426854Z","end":"2026-08-04T15:49:46.276205Z","steps":["trace[1930790565] 'agreement among raft nodes before linearized reading' (duration: 849.26504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.27623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.426843Z","time spent":"849.383212ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-04T15:49:46.276336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.919654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-rabbit-init-9dgsl\" ","response":"range_response_count:1 size:6673"} {"level":"info","ts":"2026-08-04T15:49:46.276353Z","caller":"traceutil/trace.go:171","msg":"trace[1043496532] range","detail":"{range_begin:/registry/pods/openstack/nova-rabbit-init-9dgsl; range_end:; response_count:1; response_revision:15069; }","duration":"793.976375ms","start":"2026-08-04T15:49:45.482371Z","end":"2026-08-04T15:49:46.276347Z","steps":["trace[1043496532] 'agreement among raft nodes before linearized reading' (duration: 793.928614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.27637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.482343Z","time spent":"794.022095ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":6695,"request content":"key:\"/registry/pods/openstack/nova-rabbit-init-9dgsl\" "} {"level":"warn","ts":"2026-08-04T15:49:46.276706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.235573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fnh7r\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-08-04T15:49:46.276733Z","caller":"traceutil/trace.go:171","msg":"trace[383240455] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fnh7r; range_end:; response_count:1; response_revision:15069; }","duration":"110.313644ms","start":"2026-08-04T15:49:46.166413Z","end":"2026-08-04T15:49:46.276726Z","steps":["trace[383240455] 'agreement among raft nodes before linearized reading' (duration: 110.264663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.276866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.495138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fnh7r\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-08-04T15:49:46.276884Z","caller":"traceutil/trace.go:171","msg":"trace[659398221] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fnh7r; range_end:; response_count:1; response_revision:15069; }","duration":"164.550948ms","start":"2026-08-04T15:49:46.112328Z","end":"2026-08-04T15:49:46.276879Z","steps":["trace[659398221] 'agreement among raft nodes before linearized reading' (duration: 164.494327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.276974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.504533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mdgg6\" ","response":"range_response_count:1 size:9796"} {"level":"warn","ts":"2026-08-04T15:49:46.276992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.16564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:49:46.277002Z","caller":"traceutil/trace.go:171","msg":"trace[1314471600] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mdgg6; range_end:; response_count:1; response_revision:15069; }","duration":"406.561595ms","start":"2026-08-04T15:49:45.870434Z","end":"2026-08-04T15:49:46.276995Z","steps":["trace[1314471600] 'agreement among raft nodes before linearized reading' (duration: 406.471273ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:49:46.277013Z","caller":"traceutil/trace.go:171","msg":"trace[1526677989] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:15069; }","duration":"467.227711ms","start":"2026-08-04T15:49:45.809778Z","end":"2026-08-04T15:49:46.277006Z","steps":["trace[1526677989] 'agreement among raft nodes before linearized reading' (duration: 467.191121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.277022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.870419Z","time spent":"406.597565ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9818,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-mdgg6\" "} {"level":"warn","ts":"2026-08-04T15:49:46.277019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.520908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-08-04T15:49:46.277006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.295231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:49:46.277061Z","caller":"traceutil/trace.go:171","msg":"trace[1987773018] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15069; }","duration":"196.588639ms","start":"2026-08-04T15:49:46.080461Z","end":"2026-08-04T15:49:46.277049Z","steps":["trace[1987773018] 'agreement among raft nodes before linearized reading' (duration: 196.449376ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:49:46.277072Z","caller":"traceutil/trace.go:171","msg":"trace[609741000] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:15069; }","duration":"211.417522ms","start":"2026-08-04T15:49:46.065643Z","end":"2026-08-04T15:49:46.277061Z","steps":["trace[609741000] 'agreement among raft nodes before linearized reading' (duration: 211.29469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.277119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.856006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-04T15:49:46.277143Z","caller":"traceutil/trace.go:171","msg":"trace[1722356682] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15069; }","duration":"195.894857ms","start":"2026-08-04T15:49:46.081242Z","end":"2026-08-04T15:49:46.277137Z","steps":["trace[1722356682] 'agreement among raft nodes before linearized reading' (duration: 195.830014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.277123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.404426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-04T15:49:46.277184Z","caller":"traceutil/trace.go:171","msg":"trace[273392440] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15069; }","duration":"260.50955ms","start":"2026-08-04T15:49:46.016656Z","end":"2026-08-04T15:49:46.277166Z","steps":["trace[273392440] 'agreement among raft nodes before linearized reading' (duration: 260.344486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.277217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.606431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:1 size:4957"} {"level":"info","ts":"2026-08-04T15:49:46.27724Z","caller":"traceutil/trace.go:171","msg":"trace[1095698394] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:1; response_revision:15069; }","duration":"195.642252ms","start":"2026-08-04T15:49:46.081591Z","end":"2026-08-04T15:49:46.277233Z","steps":["trace[1095698394] 'agreement among raft nodes before linearized reading' (duration: 195.577621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.277234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.087222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-04T15:49:46.277246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.367577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:1 size:4957"} {"level":"info","ts":"2026-08-04T15:49:46.277265Z","caller":"traceutil/trace.go:171","msg":"trace[858141634] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15069; }","duration":"206.119953ms","start":"2026-08-04T15:49:46.071137Z","end":"2026-08-04T15:49:46.277256Z","steps":["trace[858141634] 'agreement among raft nodes before linearized reading' (duration: 206.066232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:46.277033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:45.80976Z","time spent":"467.266732ms","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-08-04T15:49:46.277266Z","caller":"traceutil/trace.go:171","msg":"trace[1052958177] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:1; response_revision:15069; }","duration":"196.398567ms","start":"2026-08-04T15:49:46.080862Z","end":"2026-08-04T15:49:46.27726Z","steps":["trace[1052958177] 'agreement among raft nodes before linearized reading' (duration: 196.334946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:47.043062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.923058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-t7qhj\" ","response":"range_response_count:1 size:20660"} {"level":"info","ts":"2026-08-04T15:49:47.043125Z","caller":"traceutil/trace.go:171","msg":"trace[1540872991] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-t7qhj; range_end:; response_count:1; response_revision:15079; }","duration":"260.033261ms","start":"2026-08-04T15:49:46.783077Z","end":"2026-08-04T15:49:47.04311Z","steps":["trace[1540872991] 'range keys from in-memory index tree' (duration: 259.688214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:47.043213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.202691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/nova-rabbit-init-9dgsl\" ","response":"range_response_count:1 size:2777"} {"level":"info","ts":"2026-08-04T15:49:47.043278Z","caller":"traceutil/trace.go:171","msg":"trace[1990069149] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/nova-rabbit-init-9dgsl; range_end:; response_count:1; response_revision:15079; }","duration":"349.289203ms","start":"2026-08-04T15:49:46.693971Z","end":"2026-08-04T15:49:47.04326Z","steps":["trace[1990069149] 'range keys from in-memory index tree' (duration: 349.098949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:49:47.043327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:49:46.693956Z","time spent":"349.360704ms","remote":"127.0.0.1:38862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":2799,"request content":"key:\"/registry/cilium.io/ciliumendpoints/openstack/nova-rabbit-init-9dgsl\" "} {"level":"warn","ts":"2026-08-04T15:49:47.043416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.262624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-04T15:49:47.043401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.766547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zcr6g\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-04T15:49:47.043475Z","caller":"traceutil/trace.go:171","msg":"trace[386277234] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zcr6g; range_end:; response_count:1; response_revision:15079; }","duration":"290.884719ms","start":"2026-08-04T15:49:46.752571Z","end":"2026-08-04T15:49:47.043456Z","steps":["trace[386277234] 'range keys from in-memory index tree' (duration: 290.599474ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:49:47.043439Z","caller":"traceutil/trace.go:171","msg":"trace[1912776570] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:15079; }","duration":"163.327056ms","start":"2026-08-04T15:49:46.880107Z","end":"2026-08-04T15:49:47.043434Z","steps":["trace[1912776570] 'count revisions from in-memory index tree' (duration: 163.164473ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:50:07.535433Z","caller":"traceutil/trace.go:171","msg":"trace[1972261806] linearizableReadLoop","detail":"{readStateIndex:15730; appliedIndex:15729; }","duration":"494.950682ms","start":"2026-08-04T15:50:07.040447Z","end":"2026-08-04T15:50:07.535398Z","steps":["trace[1972261806] 'read index received' (duration: 494.696666ms)","trace[1972261806] 'applied index is now lower than readState.Index' (duration: 252.946µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:50:07.535463Z","caller":"traceutil/trace.go:171","msg":"trace[1423348696] transaction","detail":"{read_only:false; response_revision:15211; number_of_response:1; }","duration":"861.336921ms","start":"2026-08-04T15:50:06.674099Z","end":"2026-08-04T15:50:07.535435Z","steps":["trace[1423348696] 'process raft request' (duration: 861.107305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:50:07.535626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.148645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:50:07.535673Z","caller":"traceutil/trace.go:171","msg":"trace[647385066] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:15211; }","duration":"495.274678ms","start":"2026-08-04T15:50:07.040388Z","end":"2026-08-04T15:50:07.535662Z","steps":["trace[647385066] 'agreement among raft nodes before linearized reading' (duration: 495.135305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:50:07.535711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:50:07.040366Z","time spent":"495.337888ms","remote":"127.0.0.1:60264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:50:07.535851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.164876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zcr6g\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-04T15:50:07.535883Z","caller":"traceutil/trace.go:171","msg":"trace[2014171612] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zcr6g; range_end:; response_count:1; response_revision:15211; }","duration":"300.213378ms","start":"2026-08-04T15:50:07.235661Z","end":"2026-08-04T15:50:07.535875Z","steps":["trace[2014171612] 'agreement among raft nodes before linearized reading' (duration: 300.077865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:50:07.53591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:50:07.235646Z","time spent":"300.256488ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6552,"request content":"key:\"/registry/pods/openstack/keepalived-zcr6g\" "} {"level":"warn","ts":"2026-08-04T15:50:07.535884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.773884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-08-04T15:50:07.535919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.924794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:50:07.535974Z","caller":"traceutil/trace.go:171","msg":"trace[1732528508] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:15211; }","duration":"347.914047ms","start":"2026-08-04T15:50:07.188035Z","end":"2026-08-04T15:50:07.535949Z","steps":["trace[1732528508] 'agreement among raft nodes before linearized reading' (duration: 347.676733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:50:07.536024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:50:07.188015Z","time spent":"347.998178ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-08-04T15:50:07.536024Z","caller":"traceutil/trace.go:171","msg":"trace[1757583709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15211; }","duration":"200.034106ms","start":"2026-08-04T15:50:07.335962Z","end":"2026-08-04T15:50:07.535996Z","steps":["trace[1757583709] 'agreement among raft nodes before linearized reading' (duration: 199.872333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:50:07.536011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.740727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-t7qhj\" ","response":"range_response_count:1 size:20660"} {"level":"info","ts":"2026-08-04T15:50:07.536122Z","caller":"traceutil/trace.go:171","msg":"trace[1835368552] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-t7qhj; range_end:; response_count:1; response_revision:15211; }","duration":"300.86579ms","start":"2026-08-04T15:50:07.235234Z","end":"2026-08-04T15:50:07.5361Z","steps":["trace[1835368552] 'agreement among raft nodes before linearized reading' (duration: 300.577954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:50:07.536204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:50:07.235208Z","time spent":"300.979151ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20682,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-t7qhj\" "} {"level":"warn","ts":"2026-08-04T15:50:07.535903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:50:06.674073Z","time spent":"861.538195ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:50:07.536581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.99844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-04T15:50:07.536632Z","caller":"traceutil/trace.go:171","msg":"trace[1932876777] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15211; }","duration":"172.069201ms","start":"2026-08-04T15:50:07.364548Z","end":"2026-08-04T15:50:07.536618Z","steps":["trace[1932876777] 'agreement among raft nodes before linearized reading' (duration: 171.180065ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:50:28.096042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13035} {"level":"info","ts":"2026-08-04T15:50:28.176238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13035,"took":"77.589221ms","hash":3310414122,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":30957568,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-04T15:50:28.176302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3310414122,"revision":13035,"compact-revision":10996} {"level":"info","ts":"2026-08-04T15:51:22.734484Z","caller":"traceutil/trace.go:171","msg":"trace[1435128722] transaction","detail":"{read_only:false; response_revision:15841; number_of_response:1; }","duration":"137.410488ms","start":"2026-08-04T15:51:22.59705Z","end":"2026-08-04T15:51:22.73446Z","steps":["trace[1435128722] 'process raft request' (duration: 137.279216ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:51:22.736184Z","caller":"traceutil/trace.go:171","msg":"trace[1220504698] transaction","detail":"{read_only:false; response_revision:15843; number_of_response:1; }","duration":"134.347621ms","start":"2026-08-04T15:51:22.601814Z","end":"2026-08-04T15:51:22.736162Z","steps":["trace[1220504698] 'process raft request' (duration: 134.278849ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:51:22.736307Z","caller":"traceutil/trace.go:171","msg":"trace[1757383683] transaction","detail":"{read_only:false; response_revision:15842; number_of_response:1; }","duration":"136.153335ms","start":"2026-08-04T15:51:22.600108Z","end":"2026-08-04T15:51:22.736262Z","steps":["trace[1757383683] 'process raft request' (duration: 135.88804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:51:23.686529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.51192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fnh7r\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-08-04T15:51:23.686643Z","caller":"traceutil/trace.go:171","msg":"trace[1538680764] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fnh7r; range_end:; response_count:1; response_revision:15849; }","duration":"414.732355ms","start":"2026-08-04T15:51:23.271884Z","end":"2026-08-04T15:51:23.686616Z","steps":["trace[1538680764] 'range keys from in-memory index tree' (duration: 414.362288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:51:23.686792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.209728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fnh7r\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-08-04T15:51:23.686855Z","caller":"traceutil/trace.go:171","msg":"trace[835722978] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fnh7r; range_end:; response_count:1; response_revision:15849; }","duration":"283.299119ms","start":"2026-08-04T15:51:23.403536Z","end":"2026-08-04T15:51:23.686835Z","steps":["trace[835722978] 'range keys from in-memory index tree' (duration: 283.102676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:51:23.686944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.496046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mdgg6\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-08-04T15:51:23.687049Z","caller":"traceutil/trace.go:171","msg":"trace[2146423862] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mdgg6; range_end:; response_count:1; response_revision:15849; }","duration":"290.644489ms","start":"2026-08-04T15:51:23.396371Z","end":"2026-08-04T15:51:23.687016Z","steps":["trace[2146423862] 'range keys from in-memory index tree' (duration: 290.008247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:51:23.687337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:51:23.271866Z","time spent":"414.868437ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22154,"request content":"key:\"/registry/pods/openstack/nova-compute-default-fnh7r\" "} {"level":"warn","ts":"2026-08-04T15:54:28.214892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.414196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mdgg6\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-08-04T15:54:28.214983Z","caller":"traceutil/trace.go:171","msg":"trace[1417167787] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mdgg6; range_end:; response_count:1; response_revision:17283; }","duration":"374.542258ms","start":"2026-08-04T15:54:27.840422Z","end":"2026-08-04T15:54:28.214964Z","steps":["trace[1417167787] 'range keys from in-memory index tree' (duration: 374.208603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:54:28.215021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:54:27.840407Z","time spent":"374.605531ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9819,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-mdgg6\" "} {"level":"warn","ts":"2026-08-04T15:54:28.215209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.190705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fnh7r\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-08-04T15:54:28.215246Z","caller":"traceutil/trace.go:171","msg":"trace[1781498434] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fnh7r; range_end:; response_count:1; response_revision:17283; }","duration":"255.257006ms","start":"2026-08-04T15:54:27.959979Z","end":"2026-08-04T15:54:28.215236Z","steps":["trace[1781498434] 'range keys from in-memory index tree' (duration: 255.082263ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:55:28.10186Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15374} {"level":"info","ts":"2026-08-04T15:55:28.199511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15374,"took":"94.28097ms","hash":355790019,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":33640448,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-04T15:55:28.199572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":355790019,"revision":15374,"compact-revision":13035} {"level":"warn","ts":"2026-08-04T15:57:42.873035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.040625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-04T15:57:42.873135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.863675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-04T15:57:42.873168Z","caller":"traceutil/trace.go:171","msg":"trace[224370632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18926; }","duration":"534.227049ms","start":"2026-08-04T15:57:42.338919Z","end":"2026-08-04T15:57:42.873146Z","steps":["trace[224370632] 'range keys from in-memory index tree' (duration: 533.970174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:42.873207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:42.3389Z","time spent":"534.29977ms","remote":"127.0.0.1:35390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-04T15:57:42.873221Z","caller":"traceutil/trace.go:171","msg":"trace[1281759971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18926; }","duration":"335.011888ms","start":"2026-08-04T15:57:42.538179Z","end":"2026-08-04T15:57:42.87319Z","steps":["trace[1281759971] 'range keys from in-memory index tree' (duration: 334.716252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:42.873254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:42.538162Z","time spent":"335.083659ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-04T15:57:42.873429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.067388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T15:57:42.873471Z","caller":"traceutil/trace.go:171","msg":"trace[1755271689] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:18926; }","duration":"180.151479ms","start":"2026-08-04T15:57:42.69331Z","end":"2026-08-04T15:57:42.873462Z","steps":["trace[1755271689] 'count revisions from in-memory index tree' (duration: 180.009916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:42.873579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.09737ms","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-04T15:57:42.873624Z","caller":"traceutil/trace.go:171","msg":"trace[966911633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18926; }","duration":"286.14054ms","start":"2026-08-04T15:57:42.587462Z","end":"2026-08-04T15:57:42.873603Z","steps":["trace[966911633] 'range keys from in-memory index tree' (duration: 286.043078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:42.873625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.583573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-04T15:57:42.873642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.696641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-04T15:57:42.873672Z","caller":"traceutil/trace.go:171","msg":"trace[58713028] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18926; }","duration":"286.738562ms","start":"2026-08-04T15:57:42.586925Z","end":"2026-08-04T15:57:42.873663Z","steps":["trace[58713028] 'range keys from in-memory index tree' (duration: 286.62808ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:57:42.87367Z","caller":"traceutil/trace.go:171","msg":"trace[2060421210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18926; }","duration":"282.636144ms","start":"2026-08-04T15:57:42.591021Z","end":"2026-08-04T15:57:42.873657Z","steps":["trace[2060421210] 'range keys from in-memory index tree' (duration: 282.501011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:42.873707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.510157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-04T15:57:42.873746Z","caller":"traceutil/trace.go:171","msg":"trace[737747339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18926; }","duration":"286.554118ms","start":"2026-08-04T15:57:42.58718Z","end":"2026-08-04T15:57:42.873735Z","steps":["trace[737747339] 'range keys from in-memory index tree' (duration: 286.417045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:50.636723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186915610482011,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:57:50.872933Z","caller":"traceutil/trace.go:171","msg":"trace[1098132316] transaction","detail":"{read_only:false; response_revision:18981; number_of_response:1; }","duration":"829.256348ms","start":"2026-08-04T15:57:50.043649Z","end":"2026-08-04T15:57:50.872906Z","steps":["trace[1098132316] 'process raft request' (duration: 826.71477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:50.873083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:50.043623Z","time spent":"829.39529ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:57:50.873286Z","caller":"traceutil/trace.go:171","msg":"trace[252332446] transaction","detail":"{read_only:false; response_revision:18982; number_of_response:1; }","duration":"778.528155ms","start":"2026-08-04T15:57:50.094734Z","end":"2026-08-04T15:57:50.873262Z","steps":["trace[252332446] 'process raft request' (duration: 778.135097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:50.873428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:50.0947Z","time spent":"778.654038ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:57:50.874004Z","caller":"traceutil/trace.go:171","msg":"trace[632843083] transaction","detail":"{read_only:false; response_revision:18983; number_of_response:1; }","duration":"778.006655ms","start":"2026-08-04T15:57:50.095976Z","end":"2026-08-04T15:57:50.873983Z","steps":["trace[632843083] 'process raft request' (duration: 777.17914ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:57:50.874023Z","caller":"traceutil/trace.go:171","msg":"trace[2122892309] linearizableReadLoop","detail":"{readStateIndex:19623; appliedIndex:19620; }","duration":"737.68535ms","start":"2026-08-04T15:57:50.136283Z","end":"2026-08-04T15:57:50.873969Z","steps":["trace[2122892309] 'read index received' (duration: 733.99356ms)","trace[2122892309] 'applied index is now lower than readState.Index' (duration: 3.68768ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:57:50.874117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:50.095952Z","time spent":"778.107648ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:57:50.87416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.879183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-08-04T15:57:50.874202Z","caller":"traceutil/trace.go:171","msg":"trace[2119758618] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:18983; }","duration":"737.954305ms","start":"2026-08-04T15:57:50.136236Z","end":"2026-08-04T15:57:50.874191Z","steps":["trace[2119758618] 'agreement among raft nodes before linearized reading' (duration: 737.824663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:50.874229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:50.136223Z","time spent":"738.000866ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1884,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"warn","ts":"2026-08-04T15:57:50.874296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.130968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T15:57:50.874343Z","caller":"traceutil/trace.go:171","msg":"trace[572499572] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:18983; }","duration":"132.225809ms","start":"2026-08-04T15:57:50.742107Z","end":"2026-08-04T15:57:50.874333Z","steps":["trace[572499572] 'agreement among raft nodes before linearized reading' (duration: 132.104077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:50.874324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.88909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T15:57:50.874435Z","caller":"traceutil/trace.go:171","msg":"trace[496255477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18983; }","duration":"536.124524ms","start":"2026-08-04T15:57:50.338285Z","end":"2026-08-04T15:57:50.874409Z","steps":["trace[496255477] 'agreement among raft nodes before linearized reading' (duration: 535.958651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:50.874497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:50.338265Z","time spent":"536.218067ms","remote":"127.0.0.1:35390","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-04T15:57:50.874863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.137936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:417393"} {"level":"info","ts":"2026-08-04T15:57:50.87497Z","caller":"traceutil/trace.go:171","msg":"trace[65177630] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:18983; }","duration":"692.294059ms","start":"2026-08-04T15:57:50.182651Z","end":"2026-08-04T15:57:50.874945Z","steps":["trace[65177630] 'agreement among raft nodes before linearized reading' (duration: 691.518874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:50.876096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:50.182635Z","time spent":"692.427821ms","remote":"127.0.0.1:47560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":417415,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-08-04T15:57:55.410327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186915610482080,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:57:55.696349Z","caller":"traceutil/trace.go:171","msg":"trace[520497931] transaction","detail":"{read_only:false; response_revision:19008; number_of_response:1; }","duration":"810.528412ms","start":"2026-08-04T15:57:54.885783Z","end":"2026-08-04T15:57:55.696312Z","steps":["trace[520497931] 'process raft request' (duration: 810.3839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.696507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:54.885755Z","time spent":"810.675376ms","remote":"127.0.0.1:35640","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-04T15:57:55.698604Z","caller":"traceutil/trace.go:171","msg":"trace[81994919] linearizableReadLoop","detail":"{readStateIndex:19651; appliedIndex:19648; }","duration":"789.201358ms","start":"2026-08-04T15:57:54.909386Z","end":"2026-08-04T15:57:55.698588Z","steps":["trace[81994919] 'read index received' (duration: 786.872533ms)","trace[81994919] 'applied index is now lower than readState.Index' (duration: 2.327845ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:57:55.698903Z","caller":"traceutil/trace.go:171","msg":"trace[421212838] transaction","detail":"{read_only:false; response_revision:19009; number_of_response:1; }","duration":"811.743925ms","start":"2026-08-04T15:57:54.887149Z","end":"2026-08-04T15:57:55.698893Z","steps":["trace[421212838] 'process raft request' (duration: 811.307627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.698988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:54.887092Z","time spent":"811.840897ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:57:55.700508Z","caller":"traceutil/trace.go:171","msg":"trace[1337651408] transaction","detail":"{read_only:false; response_revision:19010; number_of_response:1; }","duration":"812.802605ms","start":"2026-08-04T15:57:54.88767Z","end":"2026-08-04T15:57:55.700472Z","steps":["trace[1337651408] 'process raft request' (duration: 810.884149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.700654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:54.887645Z","time spent":"812.930167ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:57:55.700895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.510532ms","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-04T15:57:55.700915Z","caller":"traceutil/trace.go:171","msg":"trace[2025991873] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19010; }","duration":"791.575733ms","start":"2026-08-04T15:57:54.909333Z","end":"2026-08-04T15:57:55.700909Z","steps":["trace[2025991873] 'agreement among raft nodes before linearized reading' (duration: 791.457151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.700931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:54.909315Z","time spent":"791.612494ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-04T15:57:55.701291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.867181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-04T15:57:55.70153Z","caller":"traceutil/trace.go:171","msg":"trace[1914919232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19010; }","duration":"729.912602ms","start":"2026-08-04T15:57:54.971409Z","end":"2026-08-04T15:57:55.701322Z","steps":["trace[1914919232] 'agreement among raft nodes before linearized reading' (duration: 729.78623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.70157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:54.971362Z","time spent":"730.201057ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T15:57:55.701588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.813323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-04T15:57:55.701637Z","caller":"traceutil/trace.go:171","msg":"trace[1646093364] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19010; }","duration":"727.871974ms","start":"2026-08-04T15:57:54.973755Z","end":"2026-08-04T15:57:55.701627Z","steps":["trace[1646093364] 'agreement among raft nodes before linearized reading' (duration: 727.545168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.701655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:54.973751Z","time spent":"727.899974ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-04T15:57:55.701572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.042482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-04T15:57:55.701701Z","caller":"traceutil/trace.go:171","msg":"trace[1522040721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19010; }","duration":"723.213545ms","start":"2026-08-04T15:57:54.978483Z","end":"2026-08-04T15:57:55.701696Z","steps":["trace[1522040721] 'agreement among raft nodes before linearized reading' (duration: 722.718726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.701716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:54.978474Z","time spent":"723.237535ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-04T15:57:55.701758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.529498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-08-04T15:57:55.701768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.307171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-04T15:57:55.701787Z","caller":"traceutil/trace.go:171","msg":"trace[1215156441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19010; }","duration":"729.340551ms","start":"2026-08-04T15:57:54.972441Z","end":"2026-08-04T15:57:55.701782Z","steps":["trace[1215156441] 'agreement among raft nodes before linearized reading' (duration: 729.27955ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:57:55.701795Z","caller":"traceutil/trace.go:171","msg":"trace[501487031] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:19010; }","duration":"364.579678ms","start":"2026-08-04T15:57:55.337199Z","end":"2026-08-04T15:57:55.701779Z","steps":["trace[501487031] 'agreement among raft nodes before linearized reading' (duration: 364.430716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.701814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:54.972435Z","time spent":"729.364032ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-04T15:57:55.701789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.900724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-04T15:57:55.70183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:55.337191Z","time spent":"364.62841ms","remote":"127.0.0.1:35492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":128,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-08-04T15:57:55.701858Z","caller":"traceutil/trace.go:171","msg":"trace[825397930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19010; }","duration":"727.988817ms","start":"2026-08-04T15:57:54.97385Z","end":"2026-08-04T15:57:55.701839Z","steps":["trace[825397930] 'agreement among raft nodes before linearized reading' (duration: 727.791363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.701883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.792772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-04T15:57:55.701887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:54.973846Z","time spent":"728.034647ms","remote":"127.0.0.1:35640","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-04T15:57:55.701909Z","caller":"traceutil/trace.go:171","msg":"trace[1179830230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19010; }","duration":"365.816423ms","start":"2026-08-04T15:57:55.336084Z","end":"2026-08-04T15:57:55.7019Z","steps":["trace[1179830230] 'agreement among raft nodes before linearized reading' (duration: 365.776352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.701928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:55.335999Z","time spent":"365.924484ms","remote":"127.0.0.1:35390","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-04T15:57:55.701968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.46774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T15:57:55.701987Z","caller":"traceutil/trace.go:171","msg":"trace[40906888] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:19010; }","duration":"517.526321ms","start":"2026-08-04T15:57:55.184455Z","end":"2026-08-04T15:57:55.701981Z","steps":["trace[40906888] 'agreement among raft nodes before linearized reading' (duration: 517.492161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:55.702002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:55.184438Z","time spent":"517.560221ms","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-08-04T15:57:58.146017Z","caller":"traceutil/trace.go:171","msg":"trace[1520885934] transaction","detail":"{read_only:false; response_revision:19017; number_of_response:1; }","duration":"442.740982ms","start":"2026-08-04T15:57:57.703238Z","end":"2026-08-04T15:57:58.145979Z","steps":["trace[1520885934] 'process raft request' (duration: 442.468877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:58.146219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:57.703221Z","time spent":"442.894475ms","remote":"127.0.0.1:35640","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-04T15:57:58.147208Z","caller":"traceutil/trace.go:171","msg":"trace[1351461159] transaction","detail":"{read_only:false; response_revision:19018; number_of_response:1; }","duration":"440.221036ms","start":"2026-08-04T15:57:57.706957Z","end":"2026-08-04T15:57:58.147178Z","steps":["trace[1351461159] 'process raft request' (duration: 439.91212ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:57:58.147309Z","caller":"traceutil/trace.go:171","msg":"trace[960584572] transaction","detail":"{read_only:false; response_revision:19019; number_of_response:1; }","duration":"437.233988ms","start":"2026-08-04T15:57:57.710051Z","end":"2026-08-04T15:57:58.147285Z","steps":["trace[960584572] 'process raft request' (duration: 437.124636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:58.147383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:57.70693Z","time spent":"440.357428ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:57:58.147417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:57.710029Z","time spent":"437.33189ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:57:58.147265Z","caller":"traceutil/trace.go:171","msg":"trace[1295861038] linearizableReadLoop","detail":"{readStateIndex:19661; appliedIndex:19658; }","duration":"382.233044ms","start":"2026-08-04T15:57:57.765008Z","end":"2026-08-04T15:57:58.147241Z","steps":["trace[1295861038] 'read index received' (duration: 380.747576ms)","trace[1295861038] 'applied index is now lower than readState.Index' (duration: 1.484558ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:57:58.147641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.58178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-04T15:57:58.147858Z","caller":"traceutil/trace.go:171","msg":"trace[257801746] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19019; }","duration":"382.864236ms","start":"2026-08-04T15:57:57.764973Z","end":"2026-08-04T15:57:58.147837Z","steps":["trace[257801746] 'agreement among raft nodes before linearized reading' (duration: 382.444058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:58.147919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:57.764958Z","time spent":"382.948447ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-04T15:57:58.14795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.059251ms","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-04T15:57:58.148014Z","caller":"traceutil/trace.go:171","msg":"trace[566744914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19019; }","duration":"382.179934ms","start":"2026-08-04T15:57:57.765817Z","end":"2026-08-04T15:57:58.147997Z","steps":["trace[566744914] 'agreement among raft nodes before linearized reading' (duration: 381.98183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:58.148106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:57.765798Z","time spent":"382.256135ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-04T15:57:58.148167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.496901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-04T15:57:58.148214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.207304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-04T15:57:58.148253Z","caller":"traceutil/trace.go:171","msg":"trace[156595793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19019; }","duration":"381.585233ms","start":"2026-08-04T15:57:57.766637Z","end":"2026-08-04T15:57:58.148222Z","steps":["trace[156595793] 'agreement among raft nodes before linearized reading' (duration: 381.395739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:58.148008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.326697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-04T15:57:58.148279Z","caller":"traceutil/trace.go:171","msg":"trace[803939823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19019; }","duration":"382.280336ms","start":"2026-08-04T15:57:57.765975Z","end":"2026-08-04T15:57:58.148255Z","steps":["trace[803939823] 'agreement among raft nodes before linearized reading' (duration: 382.105941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:58.148308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:57.766626Z","time spent":"381.671644ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T15:57:58.148337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:57.765969Z","time spent":"382.357807ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-04T15:57:58.148379Z","caller":"traceutil/trace.go:171","msg":"trace[1539702848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19019; }","duration":"381.693735ms","start":"2026-08-04T15:57:57.766622Z","end":"2026-08-04T15:57:58.148316Z","steps":["trace[1539702848] 'agreement among raft nodes before linearized reading' (duration: 381.266676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:58.148113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.259858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-04T15:57:58.148456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:57.766604Z","time spent":"381.814537ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-04T15:57:58.148463Z","caller":"traceutil/trace.go:171","msg":"trace[1923754139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19019; }","duration":"349.625896ms","start":"2026-08-04T15:57:57.798823Z","end":"2026-08-04T15:57:58.148449Z","steps":["trace[1923754139] 'agreement among raft nodes before linearized reading' (duration: 349.217468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:57:58.148515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:57:57.798812Z","time spent":"349.690267ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-04T15:58:29.71206Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-04T15:58:29.717602Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-04T15:58:29.717743Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-04T15:59:50.882307Z","caller":"traceutil/trace.go:171","msg":"trace[1310656837] transaction","detail":"{read_only:false; response_revision:19794; number_of_response:1; }","duration":"123.566244ms","start":"2026-08-04T15:59:50.758712Z","end":"2026-08-04T15:59:50.882278Z","steps":["trace[1310656837] 'process raft request' (duration: 123.296609ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:51.250606Z","caller":"traceutil/trace.go:171","msg":"trace[1988678733] transaction","detail":"{read_only:false; response_revision:19797; number_of_response:1; }","duration":"362.981039ms","start":"2026-08-04T15:59:50.887599Z","end":"2026-08-04T15:59:51.250581Z","steps":["trace[1988678733] 'process raft request' (duration: 362.897358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:51.250807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:59:50.887589Z","time spent":"363.133892ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:59:51.250813Z","caller":"traceutil/trace.go:171","msg":"trace[1704678016] transaction","detail":"{read_only:false; response_revision:19796; number_of_response:1; }","duration":"363.154651ms","start":"2026-08-04T15:59:50.887497Z","end":"2026-08-04T15:59:51.250652Z","steps":["trace[1704678016] 'process raft request' (duration: 362.821885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:51.251103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:59:50.887476Z","time spent":"363.483989ms","remote":"127.0.0.1:35640","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-04T15:59:51.251087Z","caller":"traceutil/trace.go:171","msg":"trace[737125475] linearizableReadLoop","detail":"{readStateIndex:20471; appliedIndex:20467; }","duration":"270.101017ms","start":"2026-08-04T15:59:50.980947Z","end":"2026-08-04T15:59:51.251048Z","steps":["trace[737125475] 'read index received' (duration: 269.396733ms)","trace[737125475] 'applied index is now lower than readState.Index' (duration: 703.004µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:59:51.251096Z","caller":"traceutil/trace.go:171","msg":"trace[271888172] transaction","detail":"{read_only:false; response_revision:19799; number_of_response:1; }","duration":"362.789786ms","start":"2026-08-04T15:59:50.888266Z","end":"2026-08-04T15:59:51.251056Z","steps":["trace[271888172] 'process raft request' (duration: 362.643452ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:51.251087Z","caller":"traceutil/trace.go:171","msg":"trace[1767064455] transaction","detail":"{read_only:false; response_revision:19798; number_of_response:1; }","duration":"363.083421ms","start":"2026-08-04T15:59:50.887894Z","end":"2026-08-04T15:59:51.250977Z","steps":["trace[1767064455] 'process raft request' (duration: 362.651232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:51.251305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:59:50.888258Z","time spent":"362.938478ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:59:51.251381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.411652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"warn","ts":"2026-08-04T15:59:51.251417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:59:50.887881Z","time spent":"363.451268ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:59:51.251447Z","caller":"traceutil/trace.go:171","msg":"trace[1494720941] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:19799; }","duration":"270.557645ms","start":"2026-08-04T15:59:50.980873Z","end":"2026-08-04T15:59:51.251431Z","steps":["trace[1494720941] 'agreement among raft nodes before linearized reading' (duration: 270.342921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:51.252066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.309913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:27 size:239433"} {"level":"info","ts":"2026-08-04T15:59:51.252177Z","caller":"traceutil/trace.go:171","msg":"trace[939648463] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:27; response_revision:19799; }","duration":"229.589287ms","start":"2026-08-04T15:59:51.022562Z","end":"2026-08-04T15:59:51.252152Z","steps":["trace[939648463] 'agreement among raft nodes before linearized reading' (duration: 228.747111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:51.481814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.261202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T15:59:51.481933Z","caller":"traceutil/trace.go:171","msg":"trace[1695607638] linearizableReadLoop","detail":"{readStateIndex:20472; appliedIndex:20471; }","duration":"196.257085ms","start":"2026-08-04T15:59:51.285653Z","end":"2026-08-04T15:59:51.48191Z","steps":["trace[1695607638] 'read index received' (duration: 85.793559ms)","trace[1695607638] 'applied index is now lower than readState.Index' (duration: 110.461216ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:59:51.482Z","caller":"traceutil/trace.go:171","msg":"trace[2002023690] transaction","detail":"{read_only:false; response_revision:19800; number_of_response:1; }","duration":"224.312878ms","start":"2026-08-04T15:59:51.257635Z","end":"2026-08-04T15:59:51.481948Z","steps":["trace[2002023690] 'process raft request' (duration: 113.837851ms)","trace[2002023690] 'compare' (duration: 110.13765ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:59:51.482291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.636263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-04T15:59:51.48251Z","caller":"traceutil/trace.go:171","msg":"trace[1452936687] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:19800; }","duration":"196.894768ms","start":"2026-08-04T15:59:51.285599Z","end":"2026-08-04T15:59:51.482494Z","steps":["trace[1452936687] 'agreement among raft nodes before linearized reading' (duration: 196.423729ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:51.690032Z","caller":"traceutil/trace.go:171","msg":"trace[1477947163] transaction","detail":"{read_only:false; response_revision:19801; number_of_response:1; }","duration":"236.092962ms","start":"2026-08-04T15:59:51.453911Z","end":"2026-08-04T15:59:51.690004Z","steps":["trace[1477947163] 'process raft request' (duration: 203.644006ms)","trace[1477947163] 'compare' (duration: 32.005538ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:59:51.690115Z","caller":"traceutil/trace.go:171","msg":"trace[1575771549] linearizableReadLoop","detail":"{readStateIndex:20473; appliedIndex:20472; }","duration":"208.091159ms","start":"2026-08-04T15:59:51.48199Z","end":"2026-08-04T15:59:51.690081Z","steps":["trace[1575771549] 'read index received' (duration: 175.667894ms)","trace[1575771549] 'applied index is now lower than readState.Index' (duration: 32.419895ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:59:51.690256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.006134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-04T15:59:51.690302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.430336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T15:59:51.690348Z","caller":"traceutil/trace.go:171","msg":"trace[259481930] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:19801; }","duration":"177.523059ms","start":"2026-08-04T15:59:51.512809Z","end":"2026-08-04T15:59:51.690332Z","steps":["trace[259481930] 'agreement among raft nodes before linearized reading' (duration: 177.424397ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:51.690359Z","caller":"traceutil/trace.go:171","msg":"trace[2053702685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19801; }","duration":"339.140557ms","start":"2026-08-04T15:59:51.351188Z","end":"2026-08-04T15:59:51.690328Z","steps":["trace[2053702685] 'agreement among raft nodes before linearized reading' (duration: 338.994844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:51.690417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:59:51.351169Z","time spent":"339.234168ms","remote":"127.0.0.1:35390","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-04T15:59:51.974539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.385526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T15:59:51.974619Z","caller":"traceutil/trace.go:171","msg":"trace[1750623225] linearizableReadLoop","detail":"{readStateIndex:20475; appliedIndex:20474; }","duration":"283.161964ms","start":"2026-08-04T15:59:51.691445Z","end":"2026-08-04T15:59:51.974607Z","steps":["trace[1750623225] 'read index received' (duration: 134.653435ms)","trace[1750623225] 'applied index is now lower than readState.Index' (duration: 148.507519ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:59:51.974706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.259225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T15:59:51.974724Z","caller":"traceutil/trace.go:171","msg":"trace[143584815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19802; }","duration":"283.310297ms","start":"2026-08-04T15:59:51.691407Z","end":"2026-08-04T15:59:51.974718Z","steps":["trace[143584815] 'agreement among raft nodes before linearized reading' (duration: 283.231765ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:51.974867Z","caller":"traceutil/trace.go:171","msg":"trace[17222775] transaction","detail":"{read_only:false; response_revision:19802; number_of_response:1; }","duration":"283.823217ms","start":"2026-08-04T15:59:51.691036Z","end":"2026-08-04T15:59:51.974859Z","steps":["trace[17222775] 'process raft request' (duration: 135.042963ms)","trace[17222775] 'compare' (duration: 148.290284ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:00:28.108374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17962} {"level":"info","ts":"2026-08-04T16:00:28.215973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17962,"took":"103.421534ms","hash":3059270914,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":30498816,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-04T16:00:28.21603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3059270914,"revision":17962,"compact-revision":15374} {"level":"info","ts":"2026-08-04T16:03:40.476124Z","caller":"traceutil/trace.go:171","msg":"trace[1103163595] linearizableReadLoop","detail":"{readStateIndex:23023; appliedIndex:23022; }","duration":"140.168349ms","start":"2026-08-04T16:03:40.335924Z","end":"2026-08-04T16:03:40.476092Z","steps":["trace[1103163595] 'read index received' (duration: 139.907164ms)","trace[1103163595] 'applied index is now lower than readState.Index' (duration: 259.715µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:03:40.476228Z","caller":"traceutil/trace.go:171","msg":"trace[120778881] transaction","detail":"{read_only:false; response_revision:22271; number_of_response:1; }","duration":"232.022531ms","start":"2026-08-04T16:03:40.244168Z","end":"2026-08-04T16:03:40.476191Z","steps":["trace[120778881] 'process raft request' (duration: 231.683135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:03:40.476438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.499745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:03:40.476505Z","caller":"traceutil/trace.go:171","msg":"trace[1574441194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22271; }","duration":"140.618937ms","start":"2026-08-04T16:03:40.33587Z","end":"2026-08-04T16:03:40.476489Z","steps":["trace[1574441194] 'agreement among raft nodes before linearized reading' (duration: 140.408573ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:04:59.089971Z","caller":"traceutil/trace.go:171","msg":"trace[548731734] transaction","detail":"{read_only:false; response_revision:23129; number_of_response:1; }","duration":"118.459697ms","start":"2026-08-04T16:04:58.971472Z","end":"2026-08-04T16:04:59.089932Z","steps":["trace[548731734] 'process raft request' (duration: 111.645458ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:04:59.090157Z","caller":"traceutil/trace.go:171","msg":"trace[790244121] transaction","detail":"{read_only:false; response_revision:23128; number_of_response:1; }","duration":"118.750473ms","start":"2026-08-04T16:04:58.971312Z","end":"2026-08-04T16:04:59.090062Z","steps":["trace[790244121] 'process raft request' (duration: 55.301309ms)","trace[790244121] 'compare' (duration: 56.38378ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:05:28.115027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20325} {"level":"info","ts":"2026-08-04T16:05:28.225854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20325,"took":"106.622073ms","hash":913745141,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":44077056,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-04T16:05:28.225905Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":913745141,"revision":20325,"compact-revision":17962} {"level":"info","ts":"2026-08-04T16:08:11.693159Z","caller":"traceutil/trace.go:171","msg":"trace[941157308] linearizableReadLoop","detail":"{readStateIndex:25458; appliedIndex:25457; }","duration":"379.001279ms","start":"2026-08-04T16:08:11.314134Z","end":"2026-08-04T16:08:11.693135Z","steps":["trace[941157308] 'read index received' (duration: 378.856436ms)","trace[941157308] 'applied index is now lower than readState.Index' (duration: 143.963µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:08:11.693245Z","caller":"traceutil/trace.go:171","msg":"trace[461336368] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"379.09601ms","start":"2026-08-04T16:08:11.314112Z","end":"2026-08-04T16:08:11.693208Z","steps":["trace[461336368] 'process raft request' (duration: 378.475999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:08:11.693378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.224803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-04T16:08:11.693404Z","caller":"traceutil/trace.go:171","msg":"trace[303757941] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:24643; }","duration":"379.281894ms","start":"2026-08-04T16:08:11.314115Z","end":"2026-08-04T16:08:11.693397Z","steps":["trace[303757941] 'agreement among raft nodes before linearized reading' (duration: 379.142181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:08:11.693429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:08:11.314108Z","time spent":"379.315435ms","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.177\" "} {"level":"warn","ts":"2026-08-04T16:08:11.693507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:08:11.31409Z","time spent":"379.237114ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:08:11.693784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.32452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:08:11.69382Z","caller":"traceutil/trace.go:171","msg":"trace[1066181473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24643; }","duration":"356.393061ms","start":"2026-08-04T16:08:11.337418Z","end":"2026-08-04T16:08:11.693811Z","steps":["trace[1066181473] 'agreement among raft nodes before linearized reading' (duration: 356.33325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:08:11.693838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:08:11.337398Z","time spent":"356.435542ms","remote":"127.0.0.1:35390","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-04T16:08:11.693829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.413601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-04T16:08:11.693973Z","caller":"traceutil/trace.go:171","msg":"trace[789238094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24643; }","duration":"375.555294ms","start":"2026-08-04T16:08:11.3184Z","end":"2026-08-04T16:08:11.693956Z","steps":["trace[789238094] 'agreement among raft nodes before linearized reading' (duration: 375.298639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:08:11.69403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:08:11.31834Z","time spent":"375.676286ms","remote":"127.0.0.1:35640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-04T16:10:28.122512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23422} {"level":"info","ts":"2026-08-04T16:10:28.258239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23422,"took":"132.751737ms","hash":4043153562,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":30711808,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-04T16:10:28.258308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4043153562,"revision":23422,"compact-revision":20325}