{"level":"warn","ts":"2026-08-10T22:30:18.229734Z","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-10T22:30:18.229902Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.9:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.9:2380","--initial-cluster=instance=https://199.19.213.9:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.9:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.9: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-10T22:30:18.230018Z","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-10T22:30:18.230037Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.9:2380"]} {"level":"info","ts":"2026-08-10T22:30:18.230067Z","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-10T22:30:18.231136Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"]} {"level":"info","ts":"2026-08-10T22:30:18.231268Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.9: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-10T22:30:18.235669Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.045922ms"} {"level":"info","ts":"2026-08-10T22:30:18.250308Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3a0c9d65f167b31b","cluster-id":"86e523b598166645"} {"level":"info","ts":"2026-08-10T22:30:18.25046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=()"} {"level":"info","ts":"2026-08-10T22:30:18.250492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 0"} {"level":"info","ts":"2026-08-10T22:30:18.250506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3a0c9d65f167b31b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-10T22:30:18.250517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 1"} {"level":"info","ts":"2026-08-10T22:30:18.250634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"} {"level":"warn","ts":"2026-08-10T22:30:18.256001Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-10T22:30:18.261483Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-10T22:30:18.263014Z","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-10T22:30:18.265763Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3a0c9d65f167b31b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-10T22:30:18.266288Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3a0c9d65f167b31b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-10T22:30:18.266388Z","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-10T22:30:18.266563Z","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-10T22:30:18.266576Z","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-10T22:30:18.269104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"} {"level":"info","ts":"2026-08-10T22:30:18.269234Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","added-peer-id":"3a0c9d65f167b31b","added-peer-peer-urls":["https://199.19.213.9:2380"]} {"level":"info","ts":"2026-08-10T22:30:18.270115Z","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-10T22:30:18.270225Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.9:2380"} {"level":"info","ts":"2026-08-10T22:30:18.270264Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.9:2380"} {"level":"info","ts":"2026-08-10T22:30:18.270434Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3a0c9d65f167b31b","initial-advertise-peer-urls":["https://199.19.213.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-10T22:30:18.270504Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-10T22:30:19.251853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b is starting a new election at term 1"} {"level":"info","ts":"2026-08-10T22:30:19.251996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-10T22:30:19.252029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgPreVoteResp from 3a0c9d65f167b31b at term 1"} {"level":"info","ts":"2026-08-10T22:30:19.252054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became candidate at term 2"} {"level":"info","ts":"2026-08-10T22:30:19.252068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgVoteResp from 3a0c9d65f167b31b at term 2"} {"level":"info","ts":"2026-08-10T22:30:19.252093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became leader at term 2"} {"level":"info","ts":"2026-08-10T22:30:19.252112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3a0c9d65f167b31b elected leader 3a0c9d65f167b31b at term 2"} {"level":"info","ts":"2026-08-10T22:30:19.253615Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3a0c9d65f167b31b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.9:2379]}","request-path":"/0/members/3a0c9d65f167b31b/attributes","cluster-id":"86e523b598166645","publish-timeout":"7s"} {"level":"info","ts":"2026-08-10T22:30:19.253648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:30:19.253783Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:30:19.253753Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:30:19.253964Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-10T22:30:19.254095Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-10T22:30:19.25493Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:30:19.255045Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:30:19.255077Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:30:19.258031Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-10T22:30:19.258231Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.9:2379"} {"level":"warn","ts":"2026-08-10T22:30:35.448824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.651217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-10T22:30:35.448961Z","caller":"traceutil/trace.go:171","msg":"trace[147575262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"130.829872ms","start":"2026-08-10T22:30:35.31809Z","end":"2026-08-10T22:30:35.44892Z","steps":["trace[147575262] 'range keys from in-memory index tree' (duration: 130.517834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:35.448844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.418507ms","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-10T22:30:35.449087Z","caller":"traceutil/trace.go:171","msg":"trace[1420015496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"172.758416ms","start":"2026-08-10T22:30:35.276322Z","end":"2026-08-10T22:30:35.449081Z","steps":["trace[1420015496] 'range keys from in-memory index tree' (duration: 172.281803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:56.370155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.05742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:30:56.370472Z","caller":"traceutil/trace.go:171","msg":"trace[752074498] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"241.325751ms","start":"2026-08-10T22:30:56.129098Z","end":"2026-08-10T22:30:56.370423Z","steps":["trace[752074498] 'process raft request' (duration: 241.174257ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:56.370571Z","caller":"traceutil/trace.go:171","msg":"trace[1856437031] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"240.901848ms","start":"2026-08-10T22:30:56.129651Z","end":"2026-08-10T22:30:56.370553Z","steps":["trace[1856437031] 'process raft request' (duration: 240.760154ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:56.3706Z","caller":"traceutil/trace.go:171","msg":"trace[619594143] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"241.414282ms","start":"2026-08-10T22:30:56.129028Z","end":"2026-08-10T22:30:56.370442Z","steps":["trace[619594143] 'process raft request' (duration: 105.418107ms)","trace[619594143] 'compare' (duration: 134.918475ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:30:56.370501Z","caller":"traceutil/trace.go:171","msg":"trace[551541792] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"240.792156ms","start":"2026-08-10T22:30:56.129687Z","end":"2026-08-10T22:30:56.370479Z","steps":["trace[551541792] 'process raft request' (duration: 240.660802ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:56.370961Z","caller":"traceutil/trace.go:171","msg":"trace[524144256] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"235.379656ms","start":"2026-08-10T22:30:56.135528Z","end":"2026-08-10T22:30:56.370907Z","steps":["trace[524144256] 'process raft request' (duration: 234.941434ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:56.372361Z","caller":"traceutil/trace.go:171","msg":"trace[1740118720] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"220.393719ms","start":"2026-08-10T22:30:56.15188Z","end":"2026-08-10T22:30:56.372274Z","steps":["trace[1740118720] 'process raft request' (duration: 220.022549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:31:02.545035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.253766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.9\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-08-10T22:31:02.545113Z","caller":"traceutil/trace.go:171","msg":"trace[1165217159] range","detail":"{range_begin:/registry/masterleases/199.19.213.9; range_end:; response_count:1; response_revision:580; }","duration":"295.388611ms","start":"2026-08-10T22:31:02.249708Z","end":"2026-08-10T22:31:02.545097Z","steps":["trace[1165217159] 'range keys from in-memory index tree' (duration: 295.089603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:31:02.545226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.979729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:31:02.545341Z","caller":"traceutil/trace.go:171","msg":"trace[335289866] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"263.621599ms","start":"2026-08-10T22:31:02.281705Z","end":"2026-08-10T22:31:02.545327Z","steps":["trace[335289866] 'read index received' (duration: 102.488676ms)","trace[335289866] 'applied index is now lower than readState.Index' (duration: 161.131803ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:31:02.545396Z","caller":"traceutil/trace.go:171","msg":"trace[146822701] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"287.120491ms","start":"2026-08-10T22:31:02.258243Z","end":"2026-08-10T22:31:02.545363Z","steps":["trace[146822701] 'process raft request' (duration: 125.939486ms)","trace[146822701] 'compare' (duration: 160.733043ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:31:02.545487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.774104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9zrtq\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-08-10T22:31:02.545526Z","caller":"traceutil/trace.go:171","msg":"trace[1773707697] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9zrtq; range_end:; response_count:1; response_revision:581; }","duration":"263.849396ms","start":"2026-08-10T22:31:02.281668Z","end":"2026-08-10T22:31:02.545517Z","steps":["trace[1773707697] 'agreement among raft nodes before linearized reading' (duration: 263.695802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:31:02.783135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.948481ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-10T22:31:02.783262Z","caller":"traceutil/trace.go:171","msg":"trace[1871926468] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"227.067985ms","start":"2026-08-10T22:31:02.556179Z","end":"2026-08-10T22:31:02.783247Z","steps":["trace[1871926468] 'read index received' (duration: 107.085603ms)","trace[1871926468] 'applied index is now lower than readState.Index' (duration: 119.980932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:31:02.783446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.254849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:31:02.783495Z","caller":"traceutil/trace.go:171","msg":"trace[1137850882] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:581; }","duration":"227.338282ms","start":"2026-08-10T22:31:02.556141Z","end":"2026-08-10T22:31:02.783479Z","steps":["trace[1137850882] 'agreement among raft nodes before linearized reading' (duration: 227.242399ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:31:02.908743Z","caller":"traceutil/trace.go:171","msg":"trace[1040110428] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"349.546325ms","start":"2026-08-10T22:31:02.559166Z","end":"2026-08-10T22:31:02.908712Z","steps":["trace[1040110428] 'process raft request' (duration: 325.358063ms)","trace[1040110428] 'compare' (duration: 23.907574ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:31:02.908767Z","caller":"traceutil/trace.go:171","msg":"trace[637891605] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"125.439892ms","start":"2026-08-10T22:31:02.783299Z","end":"2026-08-10T22:31:02.908739Z","steps":["trace[637891605] 'read index received' (duration: 101.237ms)","trace[637891605] 'applied index is now lower than readState.Index' (duration: 24.200222ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:31:02.908892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:31:02.559148Z","time spent":"349.670918ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:31:02.90899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.237602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:31:02.909039Z","caller":"traceutil/trace.go:171","msg":"trace[846368500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"306.328364ms","start":"2026-08-10T22:31:02.602699Z","end":"2026-08-10T22:31:02.909027Z","steps":["trace[846368500] 'agreement among raft nodes before linearized reading' (duration: 306.137099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:31:02.909098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:31:02.60268Z","time spent":"306.391376ms","remote":"127.0.0.1:41336","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-10T22:31:02.908751Z","caller":"traceutil/trace.go:171","msg":"trace[479353091] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"124.301201ms","start":"2026-08-10T22:31:02.784429Z","end":"2026-08-10T22:31:02.908731Z","steps":["trace[479353091] 'process raft request' (duration: 124.214699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:31:03.435735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.840849ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:31:03.435861Z","caller":"traceutil/trace.go:171","msg":"trace[2108607706] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:583; }","duration":"248.977772ms","start":"2026-08-10T22:31:03.186867Z","end":"2026-08-10T22:31:03.435845Z","steps":["trace[2108607706] 'range keys from in-memory index tree' (duration: 248.825298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:31:03.436093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.330828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-10T22:31:03.436198Z","caller":"traceutil/trace.go:171","msg":"trace[728129546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"340.474662ms","start":"2026-08-10T22:31:03.095696Z","end":"2026-08-10T22:31:03.436171Z","steps":["trace[728129546] 'range keys from in-memory index tree' (duration: 340.126003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:31:03.43628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:31:03.095677Z","time spent":"340.571345ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-10T22:31:03.491463Z","caller":"traceutil/trace.go:171","msg":"trace[811455347] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"293.391765ms","start":"2026-08-10T22:31:03.19804Z","end":"2026-08-10T22:31:03.491431Z","steps":["trace[811455347] 'read index received' (duration: 290.903946ms)","trace[811455347] 'applied index is now lower than readState.Index' (duration: 2.486589ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:31:03.491597Z","caller":"traceutil/trace.go:171","msg":"trace[386627141] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"299.430612ms","start":"2026-08-10T22:31:03.192118Z","end":"2026-08-10T22:31:03.491549Z","steps":["trace[386627141] 'process raft request' (duration: 297.561231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:31:03.491739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.682823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-10T22:31:03.491918Z","caller":"traceutil/trace.go:171","msg":"trace[761106277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"293.91405ms","start":"2026-08-10T22:31:03.197992Z","end":"2026-08-10T22:31:03.491906Z","steps":["trace[761106277] 'agreement among raft nodes before linearized reading' (duration: 293.616441ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:31:47.890713Z","caller":"traceutil/trace.go:171","msg":"trace[832181199] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"190.482392ms","start":"2026-08-10T22:31:47.700206Z","end":"2026-08-10T22:31:47.890688Z","steps":["trace[832181199] 'process raft request' (duration: 190.265286ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:31:47.892424Z","caller":"traceutil/trace.go:171","msg":"trace[349219846] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"191.864459ms","start":"2026-08-10T22:31:47.70053Z","end":"2026-08-10T22:31:47.892395Z","steps":["trace[349219846] 'process raft request' (duration: 191.739365ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:32.39562Z","caller":"traceutil/trace.go:171","msg":"trace[1281053195] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"294.126235ms","start":"2026-08-10T22:32:32.101467Z","end":"2026-08-10T22:32:32.395593Z","steps":["trace[1281053195] 'process raft request' (duration: 293.929989ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:32.401852Z","caller":"traceutil/trace.go:171","msg":"trace[1844812773] linearizableReadLoop","detail":"{readStateIndex:1875; appliedIndex:1873; }","duration":"281.216108ms","start":"2026-08-10T22:32:32.12061Z","end":"2026-08-10T22:32:32.401826Z","steps":["trace[1844812773] 'read index received' (duration: 274.780911ms)","trace[1844812773] 'applied index is now lower than readState.Index' (duration: 6.434137ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:32:32.401925Z","caller":"traceutil/trace.go:171","msg":"trace[1889620800] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"295.910258ms","start":"2026-08-10T22:32:32.105985Z","end":"2026-08-10T22:32:32.401895Z","steps":["trace[1889620800] 'process raft request' (duration: 295.710062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:32.402078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.310443ms","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-10T22:32:32.402119Z","caller":"traceutil/trace.go:171","msg":"trace[964253836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1804; }","duration":"279.388304ms","start":"2026-08-10T22:32:32.12272Z","end":"2026-08-10T22:32:32.402108Z","steps":["trace[964253836] 'agreement among raft nodes before linearized reading' (duration: 279.24807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:32.402148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.038155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.9\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-08-10T22:32:32.402216Z","caller":"traceutil/trace.go:171","msg":"trace[109763251] range","detail":"{range_begin:/registry/masterleases/199.19.213.9; range_end:; response_count:1; response_revision:1804; }","duration":"148.15685ms","start":"2026-08-10T22:32:32.25404Z","end":"2026-08-10T22:32:32.402197Z","steps":["trace[109763251] 'agreement among raft nodes before linearized reading' (duration: 147.940013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:32.402545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.941929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-10T22:32:32.402596Z","caller":"traceutil/trace.go:171","msg":"trace[60302094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1804; }","duration":"282.037952ms","start":"2026-08-10T22:32:32.120547Z","end":"2026-08-10T22:32:32.402585Z","steps":["trace[60302094] 'agreement among raft nodes before linearized reading' (duration: 281.389093ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:32.510504Z","caller":"traceutil/trace.go:171","msg":"trace[767466872] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"103.894495ms","start":"2026-08-10T22:32:32.406565Z","end":"2026-08-10T22:32:32.51046Z","steps":["trace[767466872] 'process raft request' (duration: 31.410517ms)","trace[767466872] 'compare' (duration: 72.065386ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:32:32.510612Z","caller":"traceutil/trace.go:171","msg":"trace[1303672489] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"103.663838ms","start":"2026-08-10T22:32:32.406913Z","end":"2026-08-10T22:32:32.510577Z","steps":["trace[1303672489] 'process raft request' (duration: 103.381001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:17.927207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.596446ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-10T22:33:17.927341Z","caller":"traceutil/trace.go:171","msg":"trace[2056031043] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"324.299158ms","start":"2026-08-10T22:33:17.603022Z","end":"2026-08-10T22:33:17.927322Z","steps":["trace[2056031043] 'read index received' (duration: 510.295µs)","trace[2056031043] 'applied index is now lower than readState.Index' (duration: 323.787212ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:33:17.927633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.590146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:33:17.927745Z","caller":"traceutil/trace.go:171","msg":"trace[159020919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2107; }","duration":"324.75147ms","start":"2026-08-10T22:33:17.602972Z","end":"2026-08-10T22:33:17.927723Z","steps":["trace[159020919] 'agreement among raft nodes before linearized reading' (duration: 324.39941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:17.927825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:33:17.602952Z","time spent":"324.861913ms","remote":"127.0.0.1:41336","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-10T22:33:17.929618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.32871ms","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-10T22:33:17.929705Z","caller":"traceutil/trace.go:171","msg":"trace[1321682961] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2109; }","duration":"218.501616ms","start":"2026-08-10T22:33:17.71118Z","end":"2026-08-10T22:33:17.929682Z","steps":["trace[1321682961] 'agreement among raft nodes before linearized reading' (duration: 218.241187ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:12.284289Z","caller":"traceutil/trace.go:171","msg":"trace[2131661025] linearizableReadLoop","detail":"{readStateIndex:2674; appliedIndex:2673; }","duration":"125.095922ms","start":"2026-08-10T22:34:12.159171Z","end":"2026-08-10T22:34:12.284267Z","steps":["trace[2131661025] 'read index received' (duration: 124.886496ms)","trace[2131661025] 'applied index is now lower than readState.Index' (duration: 208.726µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:12.284322Z","caller":"traceutil/trace.go:171","msg":"trace[1617769254] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"168.655053ms","start":"2026-08-10T22:34:12.115648Z","end":"2026-08-10T22:34:12.284303Z","steps":["trace[1617769254] 'process raft request' (duration: 168.478869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:12.284517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.316819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-10T22:34:12.284605Z","caller":"traceutil/trace.go:171","msg":"trace[1452048414] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2574; }","duration":"125.446153ms","start":"2026-08-10T22:34:12.159141Z","end":"2026-08-10T22:34:12.284587Z","steps":["trace[1452048414] 'agreement among raft nodes before linearized reading' (duration: 125.213746ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:45.68401Z","caller":"traceutil/trace.go:171","msg":"trace[2111728800] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"102.420004ms","start":"2026-08-10T22:34:45.581546Z","end":"2026-08-10T22:34:45.683966Z","steps":["trace[2111728800] 'process raft request' (duration: 102.26866ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:46.177302Z","caller":"traceutil/trace.go:171","msg":"trace[2062754777] linearizableReadLoop","detail":"{readStateIndex:2886; appliedIndex:2885; }","duration":"334.789388ms","start":"2026-08-10T22:34:45.842485Z","end":"2026-08-10T22:34:46.177275Z","steps":["trace[2062754777] 'read index received' (duration: 308.674995ms)","trace[2062754777] 'applied index is now lower than readState.Index' (duration: 26.112973ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:46.177456Z","caller":"traceutil/trace.go:171","msg":"trace[2144911221] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"430.97996ms","start":"2026-08-10T22:34:45.746461Z","end":"2026-08-10T22:34:46.177441Z","steps":["trace[2144911221] 'process raft request' (duration: 404.685231ms)","trace[2144911221] 'compare' (duration: 25.98222ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:46.177595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.079036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-10T22:34:46.177678Z","caller":"traceutil/trace.go:171","msg":"trace[1663987359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2778; }","duration":"335.21699ms","start":"2026-08-10T22:34:45.842443Z","end":"2026-08-10T22:34:46.17766Z","steps":["trace[1663987359] 'agreement among raft nodes before linearized reading' (duration: 334.986414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:46.177651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:45.746433Z","time spent":"431.118555ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:34:46.177726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:45.842425Z","time spent":"335.292232ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-10T22:34:46.178062Z","caller":"traceutil/trace.go:171","msg":"trace[792711049] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"260.142137ms","start":"2026-08-10T22:34:45.917888Z","end":"2026-08-10T22:34:46.17803Z","steps":["trace[792711049] 'process raft request' (duration: 259.948401ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:38:29.670686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.44056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:38:29.670789Z","caller":"traceutil/trace.go:171","msg":"trace[637077787] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4652; }","duration":"118.605525ms","start":"2026-08-10T22:38:29.552163Z","end":"2026-08-10T22:38:29.670768Z","steps":["trace[637077787] 'range keys from in-memory index tree' (duration: 118.362139ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:38:34.958359Z","caller":"traceutil/trace.go:171","msg":"trace[71420595] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"113.055622ms","start":"2026-08-10T22:38:34.845274Z","end":"2026-08-10T22:38:34.958329Z","steps":["trace[71420595] 'process raft request' (duration: 112.200297ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:38:35.583171Z","caller":"traceutil/trace.go:171","msg":"trace[1962223426] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"114.124165ms","start":"2026-08-10T22:38:35.469018Z","end":"2026-08-10T22:38:35.583142Z","steps":["trace[1962223426] 'process raft request' (duration: 112.852917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:38:37.660772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.489476ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-10T22:38:37.660923Z","caller":"traceutil/trace.go:171","msg":"trace[1548663193] linearizableReadLoop","detail":"{readStateIndex:5112; appliedIndex:5111; }","duration":"276.378101ms","start":"2026-08-10T22:38:37.384521Z","end":"2026-08-10T22:38:37.660899Z","steps":["trace[1548663193] 'read index received' (duration: 115.66977ms)","trace[1548663193] 'applied index is now lower than readState.Index' (duration: 160.706511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:38:37.661146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.599977ms","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-10T22:38:37.661233Z","caller":"traceutil/trace.go:171","msg":"trace[1798983100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4921; }","duration":"276.745892ms","start":"2026-08-10T22:38:37.384469Z","end":"2026-08-10T22:38:37.661215Z","steps":["trace[1798983100] 'agreement among raft nodes before linearized reading' (duration: 276.508976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:38:39.971451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.044863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:38:39.971618Z","caller":"traceutil/trace.go:171","msg":"trace[370093694] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"166.720876ms","start":"2026-08-10T22:38:39.804872Z","end":"2026-08-10T22:38:39.971593Z","steps":["trace[370093694] 'process raft request' (duration: 42.442276ms)","trace[370093694] 'compare' (duration: 123.895349ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:38:50.900111Z","caller":"traceutil/trace.go:171","msg":"trace[170057323] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"166.600954ms","start":"2026-08-10T22:38:50.73349Z","end":"2026-08-10T22:38:50.900091Z","steps":["trace[170057323] 'process raft request' (duration: 87.469959ms)","trace[170057323] 'compare' (duration: 78.854637ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:38:50.900097Z","caller":"traceutil/trace.go:171","msg":"trace[175447055] linearizableReadLoop","detail":"{readStateIndex:5383; appliedIndex:5382; }","duration":"130.413429ms","start":"2026-08-10T22:38:50.769645Z","end":"2026-08-10T22:38:50.900058Z","steps":["trace[175447055] 'read index received' (duration: 51.240713ms)","trace[175447055] 'applied index is now lower than readState.Index' (duration: 79.171076ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:38:50.900339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.664766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6kts7\" ","response":"range_response_count:1 size:9761"} {"level":"info","ts":"2026-08-10T22:38:50.900417Z","caller":"traceutil/trace.go:171","msg":"trace[150648241] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-6kts7; range_end:; response_count:1; response_revision:5183; }","duration":"130.79711ms","start":"2026-08-10T22:38:50.769601Z","end":"2026-08-10T22:38:50.900398Z","steps":["trace[150648241] 'agreement among raft nodes before linearized reading' (duration: 130.528412ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:38:51.085824Z","caller":"traceutil/trace.go:171","msg":"trace[941483870] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"175.128182ms","start":"2026-08-10T22:38:50.910666Z","end":"2026-08-10T22:38:51.085794Z","steps":["trace[941483870] 'process raft request' (duration: 174.964567ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:38:58.361695Z","caller":"traceutil/trace.go:171","msg":"trace[1081973466] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"133.157537ms","start":"2026-08-10T22:38:58.22849Z","end":"2026-08-10T22:38:58.361648Z","steps":["trace[1081973466] 'process raft request' (duration: 129.768819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:38:58.361839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.015996ms","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-10T22:38:58.361909Z","caller":"traceutil/trace.go:171","msg":"trace[1243856643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5344; }","duration":"119.12242ms","start":"2026-08-10T22:38:58.242774Z","end":"2026-08-10T22:38:58.361896Z","steps":["trace[1243856643] 'agreement among raft nodes before linearized reading' (duration: 118.939904ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:38:58.361676Z","caller":"traceutil/trace.go:171","msg":"trace[18392687] linearizableReadLoop","detail":"{readStateIndex:5549; appliedIndex:5548; }","duration":"118.840831ms","start":"2026-08-10T22:38:58.24281Z","end":"2026-08-10T22:38:58.361651Z","steps":["trace[18392687] 'read index received' (duration: 115.439643ms)","trace[18392687] 'applied index is now lower than readState.Index' (duration: 3.400298ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:38:58.814191Z","caller":"traceutil/trace.go:171","msg":"trace[320215189] linearizableReadLoop","detail":"{readStateIndex:5553; appliedIndex:5552; }","duration":"192.503931ms","start":"2026-08-10T22:38:58.621655Z","end":"2026-08-10T22:38:58.814159Z","steps":["trace[320215189] 'read index received' (duration: 112.078475ms)","trace[320215189] 'applied index is now lower than readState.Index' (duration: 80.424106ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:38:58.814285Z","caller":"traceutil/trace.go:171","msg":"trace[686280345] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"204.215571ms","start":"2026-08-10T22:38:58.610038Z","end":"2026-08-10T22:38:58.814254Z","steps":["trace[686280345] 'process raft request' (duration: 123.61624ms)","trace[686280345] 'compare' (duration: 80.353594ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:38:58.814429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.750687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:38:58.8145Z","caller":"traceutil/trace.go:171","msg":"trace[1460003768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5348; }","duration":"192.853191ms","start":"2026-08-10T22:38:58.621629Z","end":"2026-08-10T22:38:58.814482Z","steps":["trace[1460003768] 'agreement among raft nodes before linearized reading' (duration: 192.740848ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:38:59.087187Z","caller":"traceutil/trace.go:171","msg":"trace[910439714] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"264.842132ms","start":"2026-08-10T22:38:58.822325Z","end":"2026-08-10T22:38:59.087167Z","steps":["trace[910439714] 'process raft request' (duration: 234.926363ms)","trace[910439714] 'compare' (duration: 29.674732ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:38:59.087398Z","caller":"traceutil/trace.go:171","msg":"trace[1871860181] linearizableReadLoop","detail":"{readStateIndex:5554; appliedIndex:5553; }","duration":"209.740442ms","start":"2026-08-10T22:38:58.877381Z","end":"2026-08-10T22:38:59.087121Z","steps":["trace[1871860181] 'read index received' (duration: 179.810153ms)","trace[1871860181] 'applied index is now lower than readState.Index' (duration: 29.929349ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:38:59.087437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.04473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-10T22:38:59.087513Z","caller":"traceutil/trace.go:171","msg":"trace[1814059267] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5349; }","duration":"210.167033ms","start":"2026-08-10T22:38:58.877329Z","end":"2026-08-10T22:38:59.087496Z","steps":["trace[1814059267] 'agreement among raft nodes before linearized reading' (duration: 209.947687ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:19.292742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2961} {"level":"info","ts":"2026-08-10T22:40:19.365524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2961,"took":"70.722674ms","hash":2342096610,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":34054144,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-10T22:40:19.365611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2342096610,"revision":2961,"compact-revision":-1} {"level":"info","ts":"2026-08-10T22:45:16.187418Z","caller":"traceutil/trace.go:171","msg":"trace[148069683] transaction","detail":"{read_only:false; response_revision:8242; number_of_response:1; }","duration":"210.971066ms","start":"2026-08-10T22:45:15.976422Z","end":"2026-08-10T22:45:16.187393Z","steps":["trace[148069683] 'process raft request' (duration: 210.830663ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:16.721935Z","caller":"traceutil/trace.go:171","msg":"trace[2052288657] linearizableReadLoop","detail":"{readStateIndex:8542; appliedIndex:8541; }","duration":"182.068179ms","start":"2026-08-10T22:45:16.539836Z","end":"2026-08-10T22:45:16.721904Z","steps":["trace[2052288657] 'read index received' (duration: 105.907825ms)","trace[2052288657] 'applied index is now lower than readState.Index' (duration: 76.159264ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:45:16.722058Z","caller":"traceutil/trace.go:171","msg":"trace[586029115] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"190.217176ms","start":"2026-08-10T22:45:16.531804Z","end":"2026-08-10T22:45:16.722022Z","steps":["trace[586029115] 'process raft request' (duration: 113.852835ms)","trace[586029115] 'compare' (duration: 75.9714ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:45:16.722214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.366209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-08-10T22:45:16.722256Z","caller":"traceutil/trace.go:171","msg":"trace[968245726] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8247; }","duration":"182.43771ms","start":"2026-08-10T22:45:16.539806Z","end":"2026-08-10T22:45:16.722244Z","steps":["trace[968245726] 'agreement among raft nodes before linearized reading' (duration: 182.251225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:16.722469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.995201ms","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-10T22:45:16.722499Z","caller":"traceutil/trace.go:171","msg":"trace[1176359922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8247; }","duration":"178.051834ms","start":"2026-08-10T22:45:16.54444Z","end":"2026-08-10T22:45:16.722492Z","steps":["trace[1176359922] 'agreement among raft nodes before linearized reading' (duration: 177.970651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:16.7225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.878309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:45:16.722542Z","caller":"traceutil/trace.go:171","msg":"trace[1506419992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8247; }","duration":"120.960002ms","start":"2026-08-10T22:45:16.601569Z","end":"2026-08-10T22:45:16.722529Z","steps":["trace[1506419992] 'agreement among raft nodes before linearized reading' (duration: 120.871729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:16.940935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.927943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:45:16.941038Z","caller":"traceutil/trace.go:171","msg":"trace[2101283491] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"214.320263ms","start":"2026-08-10T22:45:16.726702Z","end":"2026-08-10T22:45:16.941022Z","steps":["trace[2101283491] 'process raft request' (duration: 105.218425ms)","trace[2101283491] 'compare' (duration: 108.775859ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:45:19.300078Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6092} {"level":"info","ts":"2026-08-10T22:45:19.395548Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6092,"took":"93.865428ms","hash":2530515912,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":23203840,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-10T22:45:19.395627Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2530515912,"revision":6092,"compact-revision":2961} {"level":"info","ts":"2026-08-10T22:45:45.066455Z","caller":"traceutil/trace.go:171","msg":"trace[650020574] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"101.443738ms","start":"2026-08-10T22:45:44.964985Z","end":"2026-08-10T22:45:45.066429Z","steps":["trace[650020574] 'process raft request' (duration: 101.258712ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:45.292875Z","caller":"traceutil/trace.go:171","msg":"trace[169369141] linearizableReadLoop","detail":"{readStateIndex:8760; appliedIndex:8759; }","duration":"176.529179ms","start":"2026-08-10T22:45:45.116327Z","end":"2026-08-10T22:45:45.292857Z","steps":["trace[169369141] 'read index received' (duration: 176.382726ms)","trace[169369141] 'applied index is now lower than readState.Index' (duration: 145.543µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:45:45.293015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.681953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-10T22:45:45.293042Z","caller":"traceutil/trace.go:171","msg":"trace[2016652592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8456; }","duration":"176.759366ms","start":"2026-08-10T22:45:45.116275Z","end":"2026-08-10T22:45:45.293035Z","steps":["trace[2016652592] 'agreement among raft nodes before linearized reading' (duration: 176.650643ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:45.293008Z","caller":"traceutil/trace.go:171","msg":"trace[966088287] transaction","detail":"{read_only:false; response_revision:8456; number_of_response:1; }","duration":"327.657031ms","start":"2026-08-10T22:45:44.965239Z","end":"2026-08-10T22:45:45.292896Z","steps":["trace[966088287] 'process raft request' (duration: 327.507166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:45.293168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:44.965214Z","time spent":"327.870276ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:45:49.458688Z","caller":"traceutil/trace.go:171","msg":"trace[71537762] linearizableReadLoop","detail":"{readStateIndex:8779; appliedIndex:8778; }","duration":"176.094096ms","start":"2026-08-10T22:45:49.282566Z","end":"2026-08-10T22:45:49.45866Z","steps":["trace[71537762] 'read index received' (duration: 173.937914ms)","trace[71537762] 'applied index is now lower than readState.Index' (duration: 2.154872ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:45:49.458833Z","caller":"traceutil/trace.go:171","msg":"trace[246433618] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"334.1813ms","start":"2026-08-10T22:45:49.124637Z","end":"2026-08-10T22:45:49.458818Z","steps":["trace[246433618] 'process raft request' (duration: 331.850823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:49.458965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:49.124597Z","time spent":"334.276452ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:45:49.459271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.214104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-10T22:45:49.45929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.728245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7576844979-4lw89.18ca931008b4e98b\" ","response":"range_response_count:1 size:1133"} {"level":"info","ts":"2026-08-10T22:45:49.459334Z","caller":"traceutil/trace.go:171","msg":"trace[1595084179] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7576844979-4lw89.18ca931008b4e98b; range_end:; response_count:1; response_revision:8474; }","duration":"176.813058ms","start":"2026-08-10T22:45:49.282507Z","end":"2026-08-10T22:45:49.45932Z","steps":["trace[1595084179] 'agreement among raft nodes before linearized reading' (duration: 176.651733ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:49.459333Z","caller":"traceutil/trace.go:171","msg":"trace[987864520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8474; }","duration":"151.295896ms","start":"2026-08-10T22:45:49.308014Z","end":"2026-08-10T22:45:49.45931Z","steps":["trace[987864520] 'agreement among raft nodes before linearized reading' (duration: 151.141431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:49.459361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.087582ms","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-10T22:45:49.459454Z","caller":"traceutil/trace.go:171","msg":"trace[382141757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8474; }","duration":"148.209536ms","start":"2026-08-10T22:45:49.311221Z","end":"2026-08-10T22:45:49.459431Z","steps":["trace[382141757] 'agreement among raft nodes before linearized reading' (duration: 147.97246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:50.756247Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906085000844270375,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-10T22:45:50.89914Z","caller":"traceutil/trace.go:171","msg":"trace[1924300930] transaction","detail":"{read_only:false; response_revision:8478; number_of_response:1; }","duration":"941.512836ms","start":"2026-08-10T22:45:49.957608Z","end":"2026-08-10T22:45:50.899121Z","steps":["trace[1924300930] 'process raft request' (duration: 941.059573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:50.899288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:49.957583Z","time spent":"941.646571ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:45:50.907188Z","caller":"traceutil/trace.go:171","msg":"trace[47280932] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"943.418272ms","start":"2026-08-10T22:45:49.963733Z","end":"2026-08-10T22:45:50.907152Z","steps":["trace[47280932] 'process raft request' (duration: 943.112593ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:50.907206Z","caller":"traceutil/trace.go:171","msg":"trace[875233306] linearizableReadLoop","detail":"{readStateIndex:8787; appliedIndex:8783; }","duration":"651.778888ms","start":"2026-08-10T22:45:50.255403Z","end":"2026-08-10T22:45:50.907182Z","steps":["trace[875233306] 'read index received' (duration: 643.260251ms)","trace[875233306] 'applied index is now lower than readState.Index' (duration: 8.515637ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:45:50.907328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:49.963717Z","time spent":"943.542726ms","remote":"127.0.0.1:59698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3657,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:45:50.907506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.120318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-10T22:45:50.907546Z","caller":"traceutil/trace.go:171","msg":"trace[1568639931] 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:8481; }","duration":"652.19279ms","start":"2026-08-10T22:45:50.25534Z","end":"2026-08-10T22:45:50.907533Z","steps":["trace[1568639931] 'agreement among raft nodes before linearized reading' (duration: 652.079927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:50.907603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:50.255318Z","time spent":"652.269322ms","remote":"127.0.0.1:41542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-08-10T22:45:50.90764Z","caller":"traceutil/trace.go:171","msg":"trace[1441770393] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"460.052967ms","start":"2026-08-10T22:45:50.447576Z","end":"2026-08-10T22:45:50.907629Z","steps":["trace[1441770393] 'process raft request' (duration: 459.415349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:50.907699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:50.447553Z","time spent":"460.117919ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:45:50.907771Z","caller":"traceutil/trace.go:171","msg":"trace[446699122] transaction","detail":"{read_only:false; response_revision:8481; number_of_response:1; }","duration":"272.411385ms","start":"2026-08-10T22:45:50.635349Z","end":"2026-08-10T22:45:50.90776Z","steps":["trace[446699122] 'process raft request' (duration: 271.783176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:50.907987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.875677ms","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-10T22:45:50.908015Z","caller":"traceutil/trace.go:171","msg":"trace[964298313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8481; }","duration":"453.93747ms","start":"2026-08-10T22:45:50.45407Z","end":"2026-08-10T22:45:50.908007Z","steps":["trace[964298313] 'agreement among raft nodes before linearized reading' (duration: 453.850767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:50.908039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:50.454051Z","time spent":"453.981601ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-10T22:45:50.908057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.646675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:45:50.908079Z","caller":"traceutil/trace.go:171","msg":"trace[535285906] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:8481; }","duration":"618.714327ms","start":"2026-08-10T22:45:50.289359Z","end":"2026-08-10T22:45:50.908073Z","steps":["trace[535285906] 'agreement among raft nodes before linearized reading' (duration: 618.492711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:50.908097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:50.289337Z","time spent":"618.754278ms","remote":"127.0.0.1:41756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-10T22:45:50.908146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.668324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8rnr6\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-08-10T22:45:50.90817Z","caller":"traceutil/trace.go:171","msg":"trace[1049576785] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8rnr6; range_end:; response_count:1; response_revision:8481; }","duration":"429.729406ms","start":"2026-08-10T22:45:50.478434Z","end":"2026-08-10T22:45:50.908163Z","steps":["trace[1049576785] 'agreement among raft nodes before linearized reading' (duration: 429.655323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:50.9082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:50.478414Z","time spent":"429.780117ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-8rnr6\" "} {"level":"warn","ts":"2026-08-10T22:45:50.908249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.238137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-10T22:45:50.908267Z","caller":"traceutil/trace.go:171","msg":"trace[713021878] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8481; }","duration":"455.287679ms","start":"2026-08-10T22:45:50.452974Z","end":"2026-08-10T22:45:50.908262Z","steps":["trace[713021878] 'agreement among raft nodes before linearized reading' (duration: 455.229377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:50.908285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:50.452958Z","time spent":"455.32265ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-10T22:45:50.908416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.466863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-10T22:45:50.908474Z","caller":"traceutil/trace.go:171","msg":"trace[1933760418] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8481; }","duration":"455.561847ms","start":"2026-08-10T22:45:50.452893Z","end":"2026-08-10T22:45:50.908455Z","steps":["trace[1933760418] 'agreement among raft nodes before linearized reading' (duration: 455.407422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:50.90851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:50.452878Z","time spent":"455.622228ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-10T22:45:57.488271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.641087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:4784"} {"level":"info","ts":"2026-08-10T22:45:57.488449Z","caller":"traceutil/trace.go:171","msg":"trace[753393632] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8556; }","duration":"303.738276ms","start":"2026-08-10T22:45:57.184673Z","end":"2026-08-10T22:45:57.488412Z","steps":["trace[753393632] 'process raft request' (duration: 194.859912ms)","trace[753393632] 'compare' (duration: 108.537154ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:45:57.488628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:57.184665Z","time spent":"303.85625ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":4807,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:45:57.488702Z","caller":"traceutil/trace.go:171","msg":"trace[1465803746] linearizableReadLoop","detail":"{readStateIndex:8867; appliedIndex:8865; }","duration":"300.997896ms","start":"2026-08-10T22:45:57.187683Z","end":"2026-08-10T22:45:57.488681Z","steps":["trace[1465803746] 'read index received' (duration: 5.226223ms)","trace[1465803746] 'applied index is now lower than readState.Index' (duration: 295.768523ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:45:57.504194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.483166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-08-10T22:45:57.504241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.327447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-10T22:45:57.504272Z","caller":"traceutil/trace.go:171","msg":"trace[598734512] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8556; }","duration":"316.581499ms","start":"2026-08-10T22:45:57.187671Z","end":"2026-08-10T22:45:57.504252Z","steps":["trace[598734512] 'agreement among raft nodes before linearized reading' (duration: 301.110739ms)","trace[598734512] 'range keys from in-memory index tree' (duration: 15.280615ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:45:57.504289Z","caller":"traceutil/trace.go:171","msg":"trace[1111071545] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8556; }","duration":"173.410509ms","start":"2026-08-10T22:45:57.330865Z","end":"2026-08-10T22:45:57.504275Z","steps":["trace[1111071545] 'agreement among raft nodes before linearized reading' (duration: 173.273335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:57.50431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:57.187665Z","time spent":"316.63736ms","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":554,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-08-10T22:45:57.50432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.821762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:45:57.504821Z","caller":"traceutil/trace.go:171","msg":"trace[1068172203] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:8556; }","duration":"300.360837ms","start":"2026-08-10T22:45:57.204443Z","end":"2026-08-10T22:45:57.504804Z","steps":["trace[1068172203] 'agreement among raft nodes before linearized reading' (duration: 299.796121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:57.504431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.370773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-f8jk2\" ","response":"range_response_count:1 size:4773"} {"level":"warn","ts":"2026-08-10T22:45:57.504875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:57.204428Z","time spent":"300.43218ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-10T22:45:57.504881Z","caller":"traceutil/trace.go:171","msg":"trace[885774574] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-f8jk2; range_end:; response_count:1; response_revision:8556; }","duration":"306.823435ms","start":"2026-08-10T22:45:57.198042Z","end":"2026-08-10T22:45:57.504866Z","steps":["trace[885774574] 'agreement among raft nodes before linearized reading' (duration: 306.30444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:57.504934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:57.198033Z","time spent":"306.884106ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4796,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-f8jk2\" "} {"level":"warn","ts":"2026-08-10T22:45:57.50444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.119758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:45:57.505073Z","caller":"traceutil/trace.go:171","msg":"trace[924012922] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:8556; }","duration":"154.792608ms","start":"2026-08-10T22:45:57.350267Z","end":"2026-08-10T22:45:57.505059Z","steps":["trace[924012922] 'agreement among raft nodes before linearized reading' (duration: 154.054408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:57.504456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.918608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-f8jk2\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-08-10T22:45:57.505189Z","caller":"traceutil/trace.go:171","msg":"trace[521707991] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-f8jk2; range_end:; response_count:1; response_revision:8556; }","duration":"308.665748ms","start":"2026-08-10T22:45:57.196499Z","end":"2026-08-10T22:45:57.505164Z","steps":["trace[521707991] 'agreement among raft nodes before linearized reading' (duration: 307.855965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:57.505314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:45:57.196482Z","time spent":"308.801073ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":2825,"request content":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-f8jk2\" "} {"level":"info","ts":"2026-08-10T22:45:58.316933Z","caller":"traceutil/trace.go:171","msg":"trace[469096699] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"146.165407ms","start":"2026-08-10T22:45:58.170743Z","end":"2026-08-10T22:45:58.316908Z","steps":["trace[469096699] 'process raft request' (duration: 145.997152ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:59.070634Z","caller":"traceutil/trace.go:171","msg":"trace[1514210598] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"139.40648ms","start":"2026-08-10T22:45:58.931206Z","end":"2026-08-10T22:45:59.070613Z","steps":["trace[1514210598] 'process raft request' (duration: 139.293307ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:59.089535Z","caller":"traceutil/trace.go:171","msg":"trace[79360595] linearizableReadLoop","detail":"{readStateIndex:8882; appliedIndex:8880; }","duration":"149.871695ms","start":"2026-08-10T22:45:58.939648Z","end":"2026-08-10T22:45:59.08952Z","steps":["trace[79360595] 'read index received' (duration: 130.936645ms)","trace[79360595] 'applied index is now lower than readState.Index' (duration: 18.93421ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:45:59.08959Z","caller":"traceutil/trace.go:171","msg":"trace[1728570654] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"156.916889ms","start":"2026-08-10T22:45:58.932656Z","end":"2026-08-10T22:45:59.089573Z","steps":["trace[1728570654] 'process raft request' (duration: 156.779165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:59.089778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.110362ms","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-10T22:45:59.089817Z","caller":"traceutil/trace.go:171","msg":"trace[880120938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8570; }","duration":"150.183985ms","start":"2026-08-10T22:45:58.939624Z","end":"2026-08-10T22:45:59.089808Z","steps":["trace[880120938] 'agreement among raft nodes before linearized reading' (duration: 149.984099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:59.089851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.185321ms","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-10T22:45:59.089925Z","caller":"traceutil/trace.go:171","msg":"trace[164792835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8570; }","duration":"143.295944ms","start":"2026-08-10T22:45:58.946614Z","end":"2026-08-10T22:45:59.08991Z","steps":["trace[164792835] 'agreement among raft nodes before linearized reading' (duration: 143.071467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:59.089985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.128471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-10T22:45:59.090037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.399049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8rnr6\" ","response":"range_response_count:1 size:6523"} {"level":"warn","ts":"2026-08-10T22:45:59.090071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.425085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-10T22:45:59.090093Z","caller":"traceutil/trace.go:171","msg":"trace[1063986616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8570; }","duration":"125.467595ms","start":"2026-08-10T22:45:58.964619Z","end":"2026-08-10T22:45:59.090087Z","steps":["trace[1063986616] 'agreement among raft nodes before linearized reading' (duration: 125.406254ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:59.090098Z","caller":"traceutil/trace.go:171","msg":"trace[650678255] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8rnr6; range_end:; response_count:1; response_revision:8570; }","duration":"110.47889ms","start":"2026-08-10T22:45:58.979605Z","end":"2026-08-10T22:45:59.090084Z","steps":["trace[650678255] 'agreement among raft nodes before linearized reading' (duration: 110.330356ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:59.09014Z","caller":"traceutil/trace.go:171","msg":"trace[1244405342] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8570; }","duration":"120.279375ms","start":"2026-08-10T22:45:58.969786Z","end":"2026-08-10T22:45:59.090066Z","steps":["trace[1244405342] 'agreement among raft nodes before linearized reading' (duration: 119.956635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:59.090868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.175278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225394"} {"level":"info","ts":"2026-08-10T22:45:59.090919Z","caller":"traceutil/trace.go:171","msg":"trace[1113169150] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8570; }","duration":"144.23068ms","start":"2026-08-10T22:45:58.946677Z","end":"2026-08-10T22:45:59.090908Z","steps":["trace[1113169150] 'agreement among raft nodes before linearized reading' (duration: 143.371455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:45:59.378506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.169877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:45:59.378609Z","caller":"traceutil/trace.go:171","msg":"trace[2048992636] linearizableReadLoop","detail":"{readStateIndex:8887; appliedIndex:8884; }","duration":"254.055752ms","start":"2026-08-10T22:45:59.124535Z","end":"2026-08-10T22:45:59.378591Z","steps":["trace[2048992636] 'read index received' (duration: 267.148µs)","trace[2048992636] 'applied index is now lower than readState.Index' (duration: 253.787584ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:45:59.378708Z","caller":"traceutil/trace.go:171","msg":"trace[1973877779] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"265.961608ms","start":"2026-08-10T22:45:59.112733Z","end":"2026-08-10T22:45:59.378694Z","steps":["trace[1973877779] 'process raft request' (duration: 98.517262ms)","trace[1973877779] 'compare' (duration: 166.981853ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:45:59.378976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.427162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-10T22:45:59.37906Z","caller":"traceutil/trace.go:171","msg":"trace[1433110353] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8575; }","duration":"254.527275ms","start":"2026-08-10T22:45:59.124509Z","end":"2026-08-10T22:45:59.379036Z","steps":["trace[1433110353] 'agreement among raft nodes before linearized reading' (duration: 254.279188ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:46:39.208631Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242b.wal"} {"level":"info","ts":"2026-08-10T22:48:01.536037Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3a0c9d65f167b31b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T22:48:01.542239Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-10T22:48:01.542326Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-10T22:50:19.30969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8268} {"level":"info","ts":"2026-08-10T22:50:19.390569Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8268,"took":"78.657929ms","hash":808005665,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":25292800,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-10T22:50:19.390628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":808005665,"revision":8268,"compact-revision":6092} {"level":"info","ts":"2026-08-10T22:50:57.92492Z","caller":"traceutil/trace.go:171","msg":"trace[657411356] linearizableReadLoop","detail":"{readStateIndex:11551; appliedIndex:11550; }","duration":"118.457997ms","start":"2026-08-10T22:50:57.806432Z","end":"2026-08-10T22:50:57.92489Z","steps":["trace[657411356] 'read index received' (duration: 118.254241ms)","trace[657411356] 'applied index is now lower than readState.Index' (duration: 202.276µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:50:57.925092Z","caller":"traceutil/trace.go:171","msg":"trace[1304181505] transaction","detail":"{read_only:false; response_revision:11163; number_of_response:1; }","duration":"150.066881ms","start":"2026-08-10T22:50:57.774991Z","end":"2026-08-10T22:50:57.925058Z","steps":["trace[1304181505] 'process raft request' (duration: 149.68455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:50:57.925158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.696314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:50:57.925213Z","caller":"traceutil/trace.go:171","msg":"trace[1985138353] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11163; }","duration":"118.807467ms","start":"2026-08-10T22:50:57.80639Z","end":"2026-08-10T22:50:57.925198Z","steps":["trace[1985138353] 'agreement among raft nodes before linearized reading' (duration: 118.700294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:50:57.925321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.845088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:50:57.925431Z","caller":"traceutil/trace.go:171","msg":"trace[1212131403] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11163; }","duration":"118.987442ms","start":"2026-08-10T22:50:57.806426Z","end":"2026-08-10T22:50:57.925414Z","steps":["trace[1212131403] 'agreement among raft nodes before linearized reading' (duration: 118.834827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:50:57.925626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.858321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:50:57.925676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.644571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-08-10T22:50:57.925694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.026082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:50:57.925696Z","caller":"traceutil/trace.go:171","msg":"trace[229769633] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11163; }","duration":"116.941804ms","start":"2026-08-10T22:50:57.808738Z","end":"2026-08-10T22:50:57.925679Z","steps":["trace[229769633] 'agreement among raft nodes before linearized reading' (duration: 116.820481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:50:57.925692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.353876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:50:57.925722Z","caller":"traceutil/trace.go:171","msg":"trace[1574147180] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11163; }","duration":"112.720043ms","start":"2026-08-10T22:50:57.812989Z","end":"2026-08-10T22:50:57.925709Z","steps":["trace[1574147180] 'agreement among raft nodes before linearized reading' (duration: 112.535127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:50:57.925653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.14908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-08-10T22:50:57.925726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.890788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-08-10T22:50:57.925761Z","caller":"traceutil/trace.go:171","msg":"trace[1745473115] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11163; }","duration":"116.433849ms","start":"2026-08-10T22:50:57.809307Z","end":"2026-08-10T22:50:57.925741Z","steps":["trace[1745473115] 'agreement among raft nodes before linearized reading' (duration: 116.329276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:50:57.92578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.975767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:50:57.925798Z","caller":"traceutil/trace.go:171","msg":"trace[1695445443] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11163; }","duration":"117.303094ms","start":"2026-08-10T22:50:57.808469Z","end":"2026-08-10T22:50:57.925772Z","steps":["trace[1695445443] 'agreement among raft nodes before linearized reading' (duration: 117.078818ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:50:57.92582Z","caller":"traceutil/trace.go:171","msg":"trace[1583147325] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11163; }","duration":"113.008851ms","start":"2026-08-10T22:50:57.812786Z","end":"2026-08-10T22:50:57.925795Z","steps":["trace[1583147325] 'agreement among raft nodes before linearized reading' (duration: 112.723703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:50:57.925822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.982707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:50:57.925846Z","caller":"traceutil/trace.go:171","msg":"trace[1037794581] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11163; }","duration":"115.042939ms","start":"2026-08-10T22:50:57.810788Z","end":"2026-08-10T22:50:57.925831Z","steps":["trace[1037794581] 'agreement among raft nodes before linearized reading' (duration: 114.949536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:50:57.925611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.549304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:50:57.925894Z","caller":"traceutil/trace.go:171","msg":"trace[927365848] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11163; }","duration":"115.08877ms","start":"2026-08-10T22:50:57.810786Z","end":"2026-08-10T22:50:57.925874Z","steps":["trace[927365848] 'agreement among raft nodes before linearized reading' (duration: 114.927626ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:50:57.925929Z","caller":"traceutil/trace.go:171","msg":"trace[76553153] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11163; }","duration":"115.906514ms","start":"2026-08-10T22:50:57.810003Z","end":"2026-08-10T22:50:57.925909Z","steps":["trace[76553153] 'agreement among raft nodes before linearized reading' (duration: 115.509853ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:50:57.925725Z","caller":"traceutil/trace.go:171","msg":"trace[1193275912] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11163; }","duration":"119.073975ms","start":"2026-08-10T22:50:57.806642Z","end":"2026-08-10T22:50:57.925716Z","steps":["trace[1193275912] 'agreement among raft nodes before linearized reading' (duration: 119.031383ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:50:58.353401Z","caller":"traceutil/trace.go:171","msg":"trace[567082038] transaction","detail":"{read_only:false; response_revision:11164; number_of_response:1; }","duration":"155.27864ms","start":"2026-08-10T22:50:58.1978Z","end":"2026-08-10T22:50:58.353079Z","steps":["trace[567082038] 'process raft request' (duration: 155.098305ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:50:58.369539Z","caller":"traceutil/trace.go:171","msg":"trace[1782972306] transaction","detail":"{read_only:false; response_revision:11165; number_of_response:1; }","duration":"126.184487ms","start":"2026-08-10T22:50:58.243326Z","end":"2026-08-10T22:50:58.369511Z","steps":["trace[1782972306] 'process raft request' (duration: 126.003442ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:19.315516Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10762} {"level":"info","ts":"2026-08-10T22:55:19.397641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10762,"took":"79.744135ms","hash":1572676209,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":27742208,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-10T22:55:19.397692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1572676209,"revision":10762,"compact-revision":8268} {"level":"info","ts":"2026-08-10T22:57:17.989108Z","caller":"traceutil/trace.go:171","msg":"trace[635347932] transaction","detail":"{read_only:false; response_revision:13716; number_of_response:1; }","duration":"198.530084ms","start":"2026-08-10T22:57:17.790554Z","end":"2026-08-10T22:57:17.989084Z","steps":["trace[635347932] 'process raft request' (duration: 198.453441ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:57:17.989188Z","caller":"traceutil/trace.go:171","msg":"trace[1923870233] transaction","detail":"{read_only:false; response_revision:13715; number_of_response:1; }","duration":"200.261624ms","start":"2026-08-10T22:57:17.788848Z","end":"2026-08-10T22:57:17.98911Z","steps":["trace[1923870233] 'process raft request' (duration: 200.056128ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:57:26.152688Z","caller":"traceutil/trace.go:171","msg":"trace[1310706965] transaction","detail":"{read_only:false; response_revision:13756; number_of_response:1; }","duration":"130.097895ms","start":"2026-08-10T22:57:26.022566Z","end":"2026-08-10T22:57:26.152664Z","steps":["trace[1310706965] 'process raft request' (duration: 129.734864ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:57:26.152869Z","caller":"traceutil/trace.go:171","msg":"trace[760228997] transaction","detail":"{read_only:false; response_revision:13757; number_of_response:1; }","duration":"130.018642ms","start":"2026-08-10T22:57:26.022819Z","end":"2026-08-10T22:57:26.152837Z","steps":["trace[760228997] 'process raft request' (duration: 129.795386ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:57:34.253381Z","caller":"traceutil/trace.go:171","msg":"trace[512259701] transaction","detail":"{read_only:false; response_revision:13811; number_of_response:1; }","duration":"328.703351ms","start":"2026-08-10T22:57:33.924638Z","end":"2026-08-10T22:57:34.253341Z","steps":["trace[512259701] 'process raft request' (duration: 328.492456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:57:34.253574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:57:33.924604Z","time spent":"328.876477ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:57:34.257744Z","caller":"traceutil/trace.go:171","msg":"trace[449827918] transaction","detail":"{read_only:false; response_revision:13812; number_of_response:1; }","duration":"181.409359ms","start":"2026-08-10T22:57:34.076311Z","end":"2026-08-10T22:57:34.25772Z","steps":["trace[449827918] 'process raft request' (duration: 181.229963ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:57:56.126995Z","caller":"traceutil/trace.go:171","msg":"trace[1417904314] transaction","detail":"{read_only:false; response_revision:14119; number_of_response:1; }","duration":"341.293616ms","start":"2026-08-10T22:57:55.785679Z","end":"2026-08-10T22:57:56.126973Z","steps":["trace[1417904314] 'process raft request' (duration: 341.06952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:57:56.127139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:57:55.785651Z","time spent":"341.41678ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:59:22.286522Z","caller":"traceutil/trace.go:171","msg":"trace[1511979906] linearizableReadLoop","detail":"{readStateIndex:15447; appliedIndex:15446; }","duration":"472.223665ms","start":"2026-08-10T22:59:21.814275Z","end":"2026-08-10T22:59:22.286499Z","steps":["trace[1511979906] 'read index received' (duration: 472.069201ms)","trace[1511979906] 'applied index is now lower than readState.Index' (duration: 153.564µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:59:22.286597Z","caller":"traceutil/trace.go:171","msg":"trace[63469551] transaction","detail":"{read_only:false; response_revision:14930; number_of_response:1; }","duration":"496.645339ms","start":"2026-08-10T22:59:21.789909Z","end":"2026-08-10T22:59:22.286554Z","steps":["trace[63469551] 'process raft request' (duration: 496.406791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:22.286676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.384981ms","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-10T22:59:22.286703Z","caller":"traceutil/trace.go:171","msg":"trace[1994992969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14930; }","duration":"472.446961ms","start":"2026-08-10T22:59:21.814246Z","end":"2026-08-10T22:59:22.286693Z","steps":["trace[1994992969] 'agreement among raft nodes before linearized reading' (duration: 472.347348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:22.286724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:59:21.814233Z","time spent":"472.485992ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-10T22:59:22.286882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:59:21.789892Z","time spent":"496.791202ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:59:22.312323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.780877ms","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-10T22:59:22.312328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.441733ms","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-10T22:59:22.312382Z","caller":"traceutil/trace.go:171","msg":"trace[462609838] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14931; }","duration":"435.848158ms","start":"2026-08-10T22:59:21.876517Z","end":"2026-08-10T22:59:22.312366Z","steps":["trace[462609838] 'agreement among raft nodes before linearized reading' (duration: 435.729614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:22.31241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:59:21.87651Z","time spent":"435.893709ms","remote":"127.0.0.1:41628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-08-10T22:59:22.312406Z","caller":"traceutil/trace.go:171","msg":"trace[522785440] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14931; }","duration":"245.548057ms","start":"2026-08-10T22:59:22.06684Z","end":"2026-08-10T22:59:22.312388Z","steps":["trace[522785440] 'agreement among raft nodes before linearized reading' (duration: 245.389382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:22.312437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.642008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-10T22:59:22.312488Z","caller":"traceutil/trace.go:171","msg":"trace[1607217418] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14931; }","duration":"437.72706ms","start":"2026-08-10T22:59:21.874747Z","end":"2026-08-10T22:59:22.312474Z","steps":["trace[1607217418] 'agreement among raft nodes before linearized reading' (duration: 437.609696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:22.312519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:59:21.874739Z","time spent":"437.772601ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-10T22:59:22.312652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.829705ms","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-10T22:59:22.312671Z","caller":"traceutil/trace.go:171","msg":"trace[1683139677] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14931; }","duration":"245.886325ms","start":"2026-08-10T22:59:22.066779Z","end":"2026-08-10T22:59:22.312666Z","steps":["trace[1683139677] 'agreement among raft nodes before linearized reading' (duration: 245.850734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:22.312313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.076495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-d9q6t\" ","response":"range_response_count:1 size:20652"} {"level":"info","ts":"2026-08-10T22:59:22.312787Z","caller":"traceutil/trace.go:171","msg":"trace[815625430] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-d9q6t; range_end:; response_count:1; response_revision:14931; }","duration":"465.601659ms","start":"2026-08-10T22:59:21.847178Z","end":"2026-08-10T22:59:22.31278Z","steps":["trace[815625430] 'agreement among raft nodes before linearized reading' (duration: 464.980132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:22.312806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:59:21.847164Z","time spent":"465.63652ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20675,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-d9q6t\" "} {"level":"warn","ts":"2026-08-10T22:59:22.312834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.517562ms","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-10T22:59:22.312874Z","caller":"traceutil/trace.go:171","msg":"trace[1385332079] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14931; }","duration":"438.598785ms","start":"2026-08-10T22:59:21.874266Z","end":"2026-08-10T22:59:22.312865Z","steps":["trace[1385332079] 'agreement among raft nodes before linearized reading' (duration: 438.537623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:22.312906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:59:21.874247Z","time spent":"438.653196ms","remote":"127.0.0.1:41628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-08-10T22:59:22.313056Z","caller":"traceutil/trace.go:171","msg":"trace[1737631991] transaction","detail":"{read_only:false; response_revision:14931; number_of_response:1; }","duration":"385.287524ms","start":"2026-08-10T22:59:21.927752Z","end":"2026-08-10T22:59:22.313039Z","steps":["trace[1737631991] 'process raft request' (duration: 384.259505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:22.313305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.343128ms","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-10T22:59:22.313291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:59:21.927727Z","time spent":"385.386776ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:59:22.313339Z","caller":"traceutil/trace.go:171","msg":"trace[1059918239] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14932; }","duration":"246.39324ms","start":"2026-08-10T22:59:22.066937Z","end":"2026-08-10T22:59:22.31333Z","steps":["trace[1059918239] 'agreement among raft nodes before linearized reading' (duration: 246.318228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:22.313356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.474426ms","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-10T22:59:22.313386Z","caller":"traceutil/trace.go:171","msg":"trace[228238701] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14932; }","duration":"234.517898ms","start":"2026-08-10T22:59:22.078859Z","end":"2026-08-10T22:59:22.313377Z","steps":["trace[228238701] 'agreement among raft nodes before linearized reading' (duration: 234.391115ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:00:19.321326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12944} {"level":"info","ts":"2026-08-10T23:00:19.397767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12944,"took":"74.321008ms","hash":951899462,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":30179328,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-10T23:00:19.397843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":951899462,"revision":12944,"compact-revision":10762} {"level":"info","ts":"2026-08-10T23:05:07.19368Z","caller":"traceutil/trace.go:171","msg":"trace[509511418] transaction","detail":"{read_only:false; response_revision:17569; number_of_response:1; }","duration":"157.372021ms","start":"2026-08-10T23:05:07.036286Z","end":"2026-08-10T23:05:07.193658Z","steps":["trace[509511418] 'process raft request' (duration: 157.243097ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:05:07.637845Z","caller":"traceutil/trace.go:171","msg":"trace[1810405437] linearizableReadLoop","detail":"{readStateIndex:18173; appliedIndex:18172; }","duration":"160.635461ms","start":"2026-08-10T23:05:07.477178Z","end":"2026-08-10T23:05:07.637814Z","steps":["trace[1810405437] 'read index received' (duration: 160.344134ms)","trace[1810405437] 'applied index is now lower than readState.Index' (duration: 290.487µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:05:07.638037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.830876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:05:07.638075Z","caller":"traceutil/trace.go:171","msg":"trace[991499884] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:17570; }","duration":"160.933821ms","start":"2026-08-10T23:05:07.477128Z","end":"2026-08-10T23:05:07.638061Z","steps":["trace[991499884] 'agreement among raft nodes before linearized reading' (duration: 160.811136ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:05:07.638211Z","caller":"traceutil/trace.go:171","msg":"trace[310560317] transaction","detail":"{read_only:false; response_revision:17570; number_of_response:1; }","duration":"272.238369ms","start":"2026-08-10T23:05:07.365934Z","end":"2026-08-10T23:05:07.638172Z","steps":["trace[310560317] 'process raft request' (duration: 271.693164ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:05:19.327884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15324} {"level":"info","ts":"2026-08-10T23:05:19.422246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15324,"took":"91.102107ms","hash":1873247141,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":32768000,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-10T23:05:19.422337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1873247141,"revision":15324,"compact-revision":12944} {"level":"info","ts":"2026-08-10T23:08:37.783222Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3a0c9d65f167b31b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:08:37.788636Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-10T23:08:37.78893Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-10T23:10:19.333499Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17795} {"level":"info","ts":"2026-08-10T23:10:19.456753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17795,"took":"118.124345ms","hash":826484212,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-10T23:10:19.456809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":826484212,"revision":17795,"compact-revision":15324} {"level":"info","ts":"2026-08-10T23:10:55.101194Z","caller":"traceutil/trace.go:171","msg":"trace[843988701] transaction","detail":"{read_only:false; response_revision:20288; number_of_response:1; }","duration":"100.325647ms","start":"2026-08-10T23:10:55.000845Z","end":"2026-08-10T23:10:55.101171Z","steps":["trace[843988701] 'process raft request' (duration: 100.173432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:10:55.372931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.281191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:10:55.373064Z","caller":"traceutil/trace.go:171","msg":"trace[1198172574] linearizableReadLoop","detail":"{readStateIndex:20982; appliedIndex:20981; }","duration":"148.312338ms","start":"2026-08-10T23:10:55.224728Z","end":"2026-08-10T23:10:55.373041Z","steps":["trace[1198172574] 'read index received' (duration: 9.515802ms)","trace[1198172574] 'applied index is now lower than readState.Index' (duration: 138.794876ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:10:55.373185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.445781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:10:55.373114Z","caller":"traceutil/trace.go:171","msg":"trace[234455213] transaction","detail":"{read_only:false; response_revision:20289; number_of_response:1; }","duration":"150.981483ms","start":"2026-08-10T23:10:55.222093Z","end":"2026-08-10T23:10:55.373074Z","steps":["trace[234455213] 'process raft request' (duration: 12.161447ms)","trace[234455213] 'compare' (duration: 138.149707ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:10:55.373239Z","caller":"traceutil/trace.go:171","msg":"trace[810999046] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:20289; }","duration":"148.572815ms","start":"2026-08-10T23:10:55.224651Z","end":"2026-08-10T23:10:55.373224Z","steps":["trace[810999046] 'agreement among raft nodes before linearized reading' (duration: 148.480252ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:10:55.37419Z","caller":"traceutil/trace.go:171","msg":"trace[709091221] transaction","detail":"{read_only:false; response_revision:20290; number_of_response:1; }","duration":"149.297285ms","start":"2026-08-10T23:10:55.224864Z","end":"2026-08-10T23:10:55.374161Z","steps":["trace[709091221] 'process raft request' (duration: 149.177962ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:10:55.390758Z","caller":"traceutil/trace.go:171","msg":"trace[1038446046] transaction","detail":"{read_only:false; response_revision:20291; number_of_response:1; }","duration":"105.221266ms","start":"2026-08-10T23:10:55.285494Z","end":"2026-08-10T23:10:55.390715Z","steps":["trace[1038446046] 'process raft request' (duration: 105.017431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:10:55.390828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.15825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-10T23:10:55.390902Z","caller":"traceutil/trace.go:171","msg":"trace[676483026] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:20291; }","duration":"101.272934ms","start":"2026-08-10T23:10:55.289612Z","end":"2026-08-10T23:10:55.390885Z","steps":["trace[676483026] 'agreement among raft nodes before linearized reading' (duration: 101.032376ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:11:11.6538Z","caller":"traceutil/trace.go:171","msg":"trace[713195353] transaction","detail":"{read_only:false; response_revision:20369; number_of_response:1; }","duration":"221.383616ms","start":"2026-08-10T23:11:11.432387Z","end":"2026-08-10T23:11:11.653771Z","steps":["trace[713195353] 'process raft request' (duration: 221.223042ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:11:11.713893Z","caller":"traceutil/trace.go:171","msg":"trace[1080640340] transaction","detail":"{read_only:false; response_revision:20371; number_of_response:1; }","duration":"276.309079ms","start":"2026-08-10T23:11:11.437541Z","end":"2026-08-10T23:11:11.71385Z","steps":["trace[1080640340] 'process raft request' (duration: 276.218647ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:11:11.713969Z","caller":"traceutil/trace.go:171","msg":"trace[435760484] transaction","detail":"{read_only:false; response_revision:20370; number_of_response:1; }","duration":"278.944055ms","start":"2026-08-10T23:11:11.434965Z","end":"2026-08-10T23:11:11.713909Z","steps":["trace[435760484] 'process raft request' (duration: 278.698569ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:11:11.885185Z","caller":"traceutil/trace.go:171","msg":"trace[1852319561] transaction","detail":"{read_only:false; response_revision:20372; number_of_response:1; }","duration":"165.25541ms","start":"2026-08-10T23:11:11.719904Z","end":"2026-08-10T23:11:11.885159Z","steps":["trace[1852319561] 'process raft request' (duration: 101.99326ms)","trace[1852319561] 'compare' (duration: 63.156947ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:11:12.173238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.188539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:11:12.173345Z","caller":"traceutil/trace.go:171","msg":"trace[524627833] transaction","detail":"{read_only:false; response_revision:20373; number_of_response:1; }","duration":"243.049858ms","start":"2026-08-10T23:11:11.930277Z","end":"2026-08-10T23:11:12.173327Z","steps":["trace[524627833] 'process raft request' (duration: 93.693833ms)","trace[524627833] 'compare' (duration: 149.069556ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:11:12.174459Z","caller":"traceutil/trace.go:171","msg":"trace[281940552] transaction","detail":"{read_only:false; response_revision:20374; number_of_response:1; }","duration":"243.49542ms","start":"2026-08-10T23:11:11.930943Z","end":"2026-08-10T23:11:12.174439Z","steps":["trace[281940552] 'process raft request' (duration: 243.353267ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:22.990824Z","caller":"traceutil/trace.go:171","msg":"trace[637201961] linearizableReadLoop","detail":"{readStateIndex:22164; appliedIndex:22163; }","duration":"157.801948ms","start":"2026-08-10T23:13:22.833002Z","end":"2026-08-10T23:13:22.990804Z","steps":["trace[637201961] 'read index received' (duration: 156.517202ms)","trace[637201961] 'applied index is now lower than readState.Index' (duration: 1.283846ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:13:22.990919Z","caller":"traceutil/trace.go:171","msg":"trace[1547956771] transaction","detail":"{read_only:false; response_revision:21426; number_of_response:1; }","duration":"166.148766ms","start":"2026-08-10T23:13:22.824762Z","end":"2026-08-10T23:13:22.99091Z","steps":["trace[1547956771] 'process raft request' (duration: 164.824928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:22.991049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.928307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:1 size:7376"} {"level":"info","ts":"2026-08-10T23:13:22.991087Z","caller":"traceutil/trace.go:171","msg":"trace[715772941] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:1; response_revision:21426; }","duration":"153.993549ms","start":"2026-08-10T23:13:22.837085Z","end":"2026-08-10T23:13:22.991078Z","steps":["trace[715772941] 'agreement among raft nodes before linearized reading' (duration: 153.872685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:22.991206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.203139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-10T23:13:22.991234Z","caller":"traceutil/trace.go:171","msg":"trace[1972258864] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:21426; }","duration":"158.267131ms","start":"2026-08-10T23:13:22.83296Z","end":"2026-08-10T23:13:22.991227Z","steps":["trace[1972258864] 'agreement among raft nodes before linearized reading' (duration: 158.191348ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:43.552941Z","caller":"traceutil/trace.go:171","msg":"trace[634366327] linearizableReadLoop","detail":"{readStateIndex:23246; appliedIndex:23245; }","duration":"107.312322ms","start":"2026-08-10T23:14:43.445579Z","end":"2026-08-10T23:14:43.552891Z","steps":["trace[634366327] 'read index received' (duration: 24.564723ms)","trace[634366327] 'applied index is now lower than readState.Index' (duration: 82.746329ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:14:43.553099Z","caller":"traceutil/trace.go:171","msg":"trace[950997424] transaction","detail":"{read_only:false; response_revision:22480; number_of_response:1; }","duration":"127.107838ms","start":"2026-08-10T23:14:43.42596Z","end":"2026-08-10T23:14:43.553067Z","steps":["trace[950997424] 'process raft request' (duration: 44.100292ms)","trace[950997424] 'compare' (duration: 82.621845ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:14:43.553547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.93467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-08-10T23:14:43.553689Z","caller":"traceutil/trace.go:171","msg":"trace[2063273137] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:22480; }","duration":"108.080804ms","start":"2026-08-10T23:14:43.445545Z","end":"2026-08-10T23:14:43.553625Z","steps":["trace[2063273137] 'agreement among raft nodes before linearized reading' (duration: 107.623461ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:43.630042Z","caller":"traceutil/trace.go:171","msg":"trace[271668094] transaction","detail":"{read_only:false; response_revision:22481; number_of_response:1; }","duration":"178.492139ms","start":"2026-08-10T23:14:43.451525Z","end":"2026-08-10T23:14:43.630018Z","steps":["trace[271668094] 'process raft request' (duration: 178.383016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:43.630501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.544534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-10T23:14:43.630574Z","caller":"traceutil/trace.go:171","msg":"trace[1031559370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22481; }","duration":"118.678827ms","start":"2026-08-10T23:14:43.511878Z","end":"2026-08-10T23:14:43.630557Z","steps":["trace[1031559370] 'agreement among raft nodes before linearized reading' (duration: 118.382039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:43.63057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.001387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmcontrolplanes.controlplane.cluster.x-k8s.io\" ","response":"range_response_count:1 size:199154"} {"level":"info","ts":"2026-08-10T23:14:43.630648Z","caller":"traceutil/trace.go:171","msg":"trace[351245748] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmcontrolplanes.controlplane.cluster.x-k8s.io; range_end:; response_count:1; response_revision:22481; }","duration":"133.134692ms","start":"2026-08-10T23:14:43.497493Z","end":"2026-08-10T23:14:43.630627Z","steps":["trace[351245748] 'agreement among raft nodes before linearized reading' (duration: 132.783601ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:49.961757Z","caller":"traceutil/trace.go:171","msg":"trace[1563569459] linearizableReadLoop","detail":"{readStateIndex:23450; appliedIndex:23449; }","duration":"294.242552ms","start":"2026-08-10T23:14:49.667493Z","end":"2026-08-10T23:14:49.961736Z","steps":["trace[1563569459] 'read index received' (duration: 294.050376ms)","trace[1563569459] 'applied index is now lower than readState.Index' (duration: 191.606µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:14:49.961818Z","caller":"traceutil/trace.go:171","msg":"trace[1248892642] transaction","detail":"{read_only:false; response_revision:22683; number_of_response:1; }","duration":"295.779226ms","start":"2026-08-10T23:14:49.666006Z","end":"2026-08-10T23:14:49.961785Z","steps":["trace[1248892642] 'process raft request' (duration: 295.54205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:49.96193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.427409ms","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-10T23:14:49.961958Z","caller":"traceutil/trace.go:171","msg":"trace[1634286778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22683; }","duration":"294.51483ms","start":"2026-08-10T23:14:49.667436Z","end":"2026-08-10T23:14:49.961951Z","steps":["trace[1634286778] 'agreement among raft nodes before linearized reading' (duration: 294.405327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:49.962177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.968379ms","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-10T23:14:49.962274Z","caller":"traceutil/trace.go:171","msg":"trace[829096663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22683; }","duration":"146.121033ms","start":"2026-08-10T23:14:49.816133Z","end":"2026-08-10T23:14:49.962254Z","steps":["trace[829096663] 'agreement among raft nodes before linearized reading' (duration: 145.825994ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:19.339583Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20105} {"level":"info","ts":"2026-08-10T23:15:19.448741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20105,"took":"105.723207ms","hash":2376455949,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":43073536,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-10T23:15:19.44882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2376455949,"revision":20105,"compact-revision":17795} {"level":"warn","ts":"2026-08-10T23:15:35.591557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.661474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T23:15:35.591694Z","caller":"traceutil/trace.go:171","msg":"trace[483209688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23336; }","duration":"114.874919ms","start":"2026-08-10T23:15:35.476792Z","end":"2026-08-10T23:15:35.591667Z","steps":["trace[483209688] 'range keys from in-memory index tree' (duration: 114.265661ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:19.345267Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23188} {"level":"info","ts":"2026-08-10T23:20:19.485499Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23188,"took":"137.264632ms","hash":1561844410,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:20:19.485554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1561844410,"revision":23188,"compact-revision":20105}