{"level":"warn","ts":"2026-10-17T10:52:00.232912Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:52:00.23305Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.222:2380","--initial-cluster=instance=https://162.253.55.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.222: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-10-17T10:52:00.233175Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:52:00.233196Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.222:2380"]} {"level":"info","ts":"2026-10-17T10:52:00.23323Z","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-10-17T10:52:00.234491Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"]} {"level":"info","ts":"2026-10-17T10:52:00.234659Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.222:2380"],"listen-peer-urls":["https://162.253.55.222:2380"],"advertise-client-urls":["https://162.253.55.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.222: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-10-17T10:52:00.237553Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.57156ms"} {"level":"info","ts":"2026-10-17T10:52:00.243877Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5a9e84e0e0a90b63","cluster-id":"c7ddd50204a2b828"} {"level":"info","ts":"2026-10-17T10:52:00.244014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T10:52:00.244055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became follower at term 0"} {"level":"info","ts":"2026-10-17T10:52:00.244088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5a9e84e0e0a90b63 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T10:52:00.244106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became follower at term 1"} {"level":"info","ts":"2026-10-17T10:52:00.244174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=(6529802611110513507)"} {"level":"warn","ts":"2026-10-17T10:52:00.250607Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T10:52:00.252571Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T10:52:00.255677Z","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-10-17T10:52:00.259489Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5a9e84e0e0a90b63","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T10:52:00.259794Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5a9e84e0e0a90b63","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T10:52:00.259866Z","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-10-17T10:52:00.260028Z","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-10-17T10:52:00.260042Z","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-10-17T10:52:00.261178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=(6529802611110513507)"} {"level":"info","ts":"2026-10-17T10:52:00.261392Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7ddd50204a2b828","local-member-id":"5a9e84e0e0a90b63","added-peer-id":"5a9e84e0e0a90b63","added-peer-peer-urls":["https://162.253.55.222:2380"]} {"level":"info","ts":"2026-10-17T10:52:00.270565Z","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-10-17T10:52:00.270732Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.222:2380"} {"level":"info","ts":"2026-10-17T10:52:00.270778Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.222:2380"} {"level":"info","ts":"2026-10-17T10:52:00.272066Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5a9e84e0e0a90b63","initial-advertise-peer-urls":["https://162.253.55.222:2380"],"listen-peer-urls":["https://162.253.55.222:2380"],"advertise-client-urls":["https://162.253.55.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T10:52:00.272121Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T10:52:00.544466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T10:52:00.544535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T10:52:00.544571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 received MsgPreVoteResp from 5a9e84e0e0a90b63 at term 1"} {"level":"info","ts":"2026-10-17T10:52:00.544592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became candidate at term 2"} {"level":"info","ts":"2026-10-17T10:52:00.544604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 received MsgVoteResp from 5a9e84e0e0a90b63 at term 2"} {"level":"info","ts":"2026-10-17T10:52:00.544651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became leader at term 2"} {"level":"info","ts":"2026-10-17T10:52:00.544666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5a9e84e0e0a90b63 elected leader 5a9e84e0e0a90b63 at term 2"} {"level":"info","ts":"2026-10-17T10:52:00.545673Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:52:00.546692Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:52:00.546683Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5a9e84e0e0a90b63","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.222:2379]}","request-path":"/0/members/5a9e84e0e0a90b63/attributes","cluster-id":"c7ddd50204a2b828","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T10:52:00.546778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:52:00.547143Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T10:52:00.547221Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T10:52:00.547407Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7ddd50204a2b828","local-member-id":"5a9e84e0e0a90b63","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:52:00.54755Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:52:00.54761Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:52:00.551Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-17T10:52:00.553094Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.222:2379"} {"level":"info","ts":"2026-10-17T10:52:26.356672Z","caller":"traceutil/trace.go:171","msg":"trace[737632467] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"114.376584ms","start":"2026-10-17T10:52:26.242274Z","end":"2026-10-17T10:52:26.356651Z","steps":["trace[737632467] 'process raft request' (duration: 114.229371ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.619419Z","caller":"traceutil/trace.go:171","msg":"trace[1666813289] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"180.582678ms","start":"2026-10-17T10:52:43.438806Z","end":"2026-10-17T10:52:43.619389Z","steps":["trace[1666813289] 'process raft request' (duration: 180.323454ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.619517Z","caller":"traceutil/trace.go:171","msg":"trace[2006321084] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"177.971258ms","start":"2026-10-17T10:52:43.441495Z","end":"2026-10-17T10:52:43.619466Z","steps":["trace[2006321084] 'process raft request' (duration: 177.811824ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.619537Z","caller":"traceutil/trace.go:171","msg":"trace[764258141] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"177.754074ms","start":"2026-10-17T10:52:43.441741Z","end":"2026-10-17T10:52:43.619495Z","steps":["trace[764258141] 'process raft request' (duration: 177.634862ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.619707Z","caller":"traceutil/trace.go:171","msg":"trace[226377197] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"176.560911ms","start":"2026-10-17T10:52:43.443097Z","end":"2026-10-17T10:52:43.619658Z","steps":["trace[226377197] 'process raft request' (duration: 176.305276ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.619744Z","caller":"traceutil/trace.go:171","msg":"trace[2097074038] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"172.619716ms","start":"2026-10-17T10:52:43.447107Z","end":"2026-10-17T10:52:43.619727Z","steps":["trace[2097074038] 'process raft request' (duration: 172.32692ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.619762Z","caller":"traceutil/trace.go:171","msg":"trace[184372910] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"172.022905ms","start":"2026-10-17T10:52:43.447711Z","end":"2026-10-17T10:52:43.619734Z","steps":["trace[184372910] 'process raft request' (duration: 171.79981ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.619815Z","caller":"traceutil/trace.go:171","msg":"trace[1185686517] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"170.70022ms","start":"2026-10-17T10:52:43.449088Z","end":"2026-10-17T10:52:43.619789Z","steps":["trace[1185686517] 'process raft request' (duration: 170.530687ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.625744Z","caller":"traceutil/trace.go:171","msg":"trace[2123882025] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"167.60283ms","start":"2026-10-17T10:52:43.458111Z","end":"2026-10-17T10:52:43.625714Z","steps":["trace[2123882025] 'process raft request' (duration: 167.395546ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.6258Z","caller":"traceutil/trace.go:171","msg":"trace[90856991] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"166.092453ms","start":"2026-10-17T10:52:43.459682Z","end":"2026-10-17T10:52:43.625775Z","steps":["trace[90856991] 'process raft request' (duration: 165.883688ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.625832Z","caller":"traceutil/trace.go:171","msg":"trace[1769369330] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"162.931732ms","start":"2026-10-17T10:52:43.462863Z","end":"2026-10-17T10:52:43.625794Z","steps":["trace[1769369330] 'process raft request' (duration: 162.756798ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.62591Z","caller":"traceutil/trace.go:171","msg":"trace[1422580045] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"169.903434ms","start":"2026-10-17T10:52:43.455983Z","end":"2026-10-17T10:52:43.625887Z","steps":["trace[1422580045] 'process raft request' (duration: 169.262231ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.626159Z","caller":"traceutil/trace.go:171","msg":"trace[1191275350] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"150.323601ms","start":"2026-10-17T10:52:43.475822Z","end":"2026-10-17T10:52:43.626145Z","steps":["trace[1191275350] 'process raft request' (duration: 149.881952ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.626351Z","caller":"traceutil/trace.go:171","msg":"trace[1713185742] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"115.785661ms","start":"2026-10-17T10:52:43.510544Z","end":"2026-10-17T10:52:43.62633Z","steps":["trace[1713185742] 'process raft request' (duration: 115.528706ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:50.190024Z","caller":"traceutil/trace.go:171","msg":"trace[898231400] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"164.216687ms","start":"2026-10-17T10:52:50.025773Z","end":"2026-10-17T10:52:50.189989Z","steps":["trace[898231400] 'process raft request' (duration: 164.044024ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:51.014541Z","caller":"traceutil/trace.go:171","msg":"trace[297499632] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"405.120969ms","start":"2026-10-17T10:52:50.60937Z","end":"2026-10-17T10:52:51.014491Z","steps":["trace[297499632] 'process raft request' (duration: 402.07183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:52:51.014823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.217096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T10:52:51.014987Z","caller":"traceutil/trace.go:171","msg":"trace[532740421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"319.419101ms","start":"2026-10-17T10:52:50.695518Z","end":"2026-10-17T10:52:51.014937Z","steps":["trace[532740421] 'agreement among raft nodes before linearized reading' (duration: 319.055333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:52:51.015042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:52:50.695497Z","time spent":"319.528703ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-17T10:52:51.014585Z","caller":"traceutil/trace.go:171","msg":"trace[1138913920] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"318.90671ms","start":"2026-10-17T10:52:50.695566Z","end":"2026-10-17T10:52:51.014472Z","steps":["trace[1138913920] 'read index received' (duration: 315.103867ms)","trace[1138913920] 'applied index is now lower than readState.Index' (duration: 3.794653ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:52:51.016084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:52:50.609345Z","time spent":"405.440695ms","remote":"127.0.0.1:47604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:54:19.569152Z","caller":"traceutil/trace.go:171","msg":"trace[800758921] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"190.105032ms","start":"2026-10-17T10:54:19.379008Z","end":"2026-10-17T10:54:19.569113Z","steps":["trace[800758921] 'process raft request' (duration: 189.705154ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:19.647556Z","caller":"traceutil/trace.go:171","msg":"trace[1399728281] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1895; }","duration":"149.113253ms","start":"2026-10-17T10:54:19.498416Z","end":"2026-10-17T10:54:19.647529Z","steps":["trace[1399728281] 'read index received' (duration: 70.287189ms)","trace[1399728281] 'applied index is now lower than readState.Index' (duration: 78.824394ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:54:19.647975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.525091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T10:54:19.648448Z","caller":"traceutil/trace.go:171","msg":"trace[1353838717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1826; }","duration":"150.060332ms","start":"2026-10-17T10:54:19.498365Z","end":"2026-10-17T10:54:19.648426Z","steps":["trace[1353838717] 'agreement among raft nodes before linearized reading' (duration: 149.357718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:54:19.64833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.738206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T10:54:19.648548Z","caller":"traceutil/trace.go:171","msg":"trace[507121135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1826; }","duration":"149.993501ms","start":"2026-10-17T10:54:19.498535Z","end":"2026-10-17T10:54:19.648528Z","steps":["trace[507121135] 'agreement among raft nodes before linearized reading' (duration: 149.206076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:54:20.011651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.912807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T10:54:20.011766Z","caller":"traceutil/trace.go:171","msg":"trace[1677676524] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1830; }","duration":"115.102361ms","start":"2026-10-17T10:54:19.896644Z","end":"2026-10-17T10:54:20.011746Z","steps":["trace[1677676524] 'count revisions from in-memory index tree' (duration: 114.775395ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:06.025981Z","caller":"traceutil/trace.go:171","msg":"trace[1746733247] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"102.340639ms","start":"2026-10-17T11:01:05.923605Z","end":"2026-10-17T11:01:06.025946Z","steps":["trace[1746733247] 'process raft request' (duration: 102.070484ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:10.197041Z","caller":"traceutil/trace.go:171","msg":"trace[1938320620] linearizableReadLoop","detail":"{readStateIndex:5397; appliedIndex:5396; }","duration":"140.978408ms","start":"2026-10-17T11:01:10.056047Z","end":"2026-10-17T11:01:10.197026Z","steps":["trace[1938320620] 'read index received' (duration: 140.926787ms)","trace[1938320620] 'applied index is now lower than readState.Index' (duration: 50.851µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:01:10.19714Z","caller":"traceutil/trace.go:171","msg":"trace[1402566357] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"143.132068ms","start":"2026-10-17T11:01:10.053981Z","end":"2026-10-17T11:01:10.197113Z","steps":["trace[1402566357] 'process raft request' (duration: 142.942755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:10.197197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.144342ms","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-10-17T11:01:10.197219Z","caller":"traceutil/trace.go:171","msg":"trace[849706724] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5201; }","duration":"141.207612ms","start":"2026-10-17T11:01:10.056004Z","end":"2026-10-17T11:01:10.197211Z","steps":["trace[849706724] 'agreement among raft nodes before linearized reading' (duration: 141.120201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:10.197311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.583531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T11:01:10.197393Z","caller":"traceutil/trace.go:171","msg":"trace[1043056760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5201; }","duration":"140.715443ms","start":"2026-10-17T11:01:10.056658Z","end":"2026-10-17T11:01:10.197374Z","steps":["trace[1043056760] 'agreement among raft nodes before linearized reading' (duration: 140.500619ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:10.466434Z","caller":"traceutil/trace.go:171","msg":"trace[902187712] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"265.306892ms","start":"2026-10-17T11:01:10.201097Z","end":"2026-10-17T11:01:10.466404Z","steps":["trace[902187712] 'process raft request' (duration: 264.504887ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:10.528617Z","caller":"traceutil/trace.go:171","msg":"trace[1353270087] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"327.145057ms","start":"2026-10-17T11:01:10.201447Z","end":"2026-10-17T11:01:10.528592Z","steps":["trace[1353270087] 'process raft request' (duration: 293.32248ms)","trace[1353270087] 'compare' (duration: 33.611104ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:01:10.528776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:01:10.20143Z","time spent":"327.26557ms","remote":"127.0.0.1:47754","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-10-17T11:01:10.528952Z","caller":"traceutil/trace.go:171","msg":"trace[1924757501] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"153.598375ms","start":"2026-10-17T11:01:10.375343Z","end":"2026-10-17T11:01:10.528941Z","steps":["trace[1924757501] 'process raft request' (duration: 153.177668ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:10.528955Z","caller":"traceutil/trace.go:171","msg":"trace[1043932964] linearizableReadLoop","detail":"{readStateIndex:5399; appliedIndex:5397; }","duration":"280.906755ms","start":"2026-10-17T11:01:10.24802Z","end":"2026-10-17T11:01:10.528926Z","steps":["trace[1043932964] 'read index received' (duration: 217.650933ms)","trace[1043932964] 'applied index is now lower than readState.Index' (duration: 63.254412ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:01:10.529035Z","caller":"traceutil/trace.go:171","msg":"trace[1404914684] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"139.069691ms","start":"2026-10-17T11:01:10.389931Z","end":"2026-10-17T11:01:10.529001Z","steps":["trace[1404914684] 'process raft request' (duration: 138.871348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:10.529122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.070228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:01:10.529213Z","caller":"traceutil/trace.go:171","msg":"trace[670143235] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:5205; }","duration":"281.202881ms","start":"2026-10-17T11:01:10.247989Z","end":"2026-10-17T11:01:10.529192Z","steps":["trace[670143235] 'agreement among raft nodes before linearized reading' (duration: 281.022398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:10.529227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.776522ms","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-10-17T11:01:10.529259Z","caller":"traceutil/trace.go:171","msg":"trace[211733359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5205; }","duration":"125.825942ms","start":"2026-10-17T11:01:10.403423Z","end":"2026-10-17T11:01:10.529249Z","steps":["trace[211733359] 'agreement among raft nodes before linearized reading' (duration: 125.753371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:10.529155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.213521ms","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-10-17T11:01:10.529311Z","caller":"traceutil/trace.go:171","msg":"trace[2901398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5205; }","duration":"141.390185ms","start":"2026-10-17T11:01:10.387904Z","end":"2026-10-17T11:01:10.529294Z","steps":["trace[2901398] 'agreement among raft nodes before linearized reading' (duration: 141.164601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:11.039477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.079737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:01:11.039614Z","caller":"traceutil/trace.go:171","msg":"trace[1678847944] linearizableReadLoop","detail":"{readStateIndex:5406; appliedIndex:5405; }","duration":"243.923648ms","start":"2026-10-17T11:01:10.795667Z","end":"2026-10-17T11:01:11.039591Z","steps":["trace[1678847944] 'read index received' (duration: 57.446113ms)","trace[1678847944] 'applied index is now lower than readState.Index' (duration: 186.475435ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:01:11.039733Z","caller":"traceutil/trace.go:171","msg":"trace[1115711522] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"338.596643ms","start":"2026-10-17T11:01:10.701119Z","end":"2026-10-17T11:01:11.039715Z","steps":["trace[1115711522] 'process raft request' (duration: 152.005356ms)","trace[1115711522] 'compare' (duration: 185.878284ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:01:11.03985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:01:10.701099Z","time spent":"338.708805ms","remote":"127.0.0.1:48618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2499,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T11:01:11.040141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.460359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-17T11:01:11.040177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.303652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-17T11:01:11.040223Z","caller":"traceutil/trace.go:171","msg":"trace[638375089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5210; }","duration":"244.631442ms","start":"2026-10-17T11:01:10.795571Z","end":"2026-10-17T11:01:11.040203Z","steps":["trace[638375089] 'agreement among raft nodes before linearized reading' (duration: 244.365237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:11.040137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.87972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e80fdf07-d9a5-4c28-86d2-57ad75a7cbed\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-17T11:01:11.040241Z","caller":"traceutil/trace.go:171","msg":"trace[1643061962] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5210; }","duration":"195.397443ms","start":"2026-10-17T11:01:10.844826Z","end":"2026-10-17T11:01:11.040224Z","steps":["trace[1643061962] 'agreement among raft nodes before linearized reading' (duration: 195.116528ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:11.040381Z","caller":"traceutil/trace.go:171","msg":"trace[1349117233] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e80fdf07-d9a5-4c28-86d2-57ad75a7cbed; range_end:; response_count:1; response_revision:5210; }","duration":"225.110994ms","start":"2026-10-17T11:01:10.81521Z","end":"2026-10-17T11:01:11.040321Z","steps":["trace[1349117233] 'agreement among raft nodes before linearized reading' (duration: 224.734507ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:11.25425Z","caller":"traceutil/trace.go:171","msg":"trace[11970142] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"207.955599ms","start":"2026-10-17T11:01:11.046255Z","end":"2026-10-17T11:01:11.254211Z","steps":["trace[11970142] 'process raft request' (duration: 112.686903ms)","trace[11970142] 'compare' (duration: 94.94007ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:01:11.254673Z","caller":"traceutil/trace.go:171","msg":"trace[1123136919] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"207.616364ms","start":"2026-10-17T11:01:11.047022Z","end":"2026-10-17T11:01:11.254638Z","steps":["trace[1123136919] 'process raft request' (duration: 207.353869ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:11.254832Z","caller":"traceutil/trace.go:171","msg":"trace[2035103189] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"206.278428ms","start":"2026-10-17T11:01:11.048492Z","end":"2026-10-17T11:01:11.254771Z","steps":["trace[2035103189] 'process raft request' (duration: 206.012363ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:37.371039Z","caller":"traceutil/trace.go:171","msg":"trace[1601207788] linearizableReadLoop","detail":"{readStateIndex:5914; appliedIndex:5913; }","duration":"128.506813ms","start":"2026-10-17T11:01:37.242497Z","end":"2026-10-17T11:01:37.371004Z","steps":["trace[1601207788] 'read index received' (duration: 128.193047ms)","trace[1601207788] 'applied index is now lower than readState.Index' (duration: 312.566µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:01:37.3711Z","caller":"traceutil/trace.go:171","msg":"trace[1914075555] transaction","detail":"{read_only:false; response_revision:5704; number_of_response:1; }","duration":"192.02975ms","start":"2026-10-17T11:01:37.179031Z","end":"2026-10-17T11:01:37.371061Z","steps":["trace[1914075555] 'process raft request' (duration: 191.751615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:37.371253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.729757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:01:37.371318Z","caller":"traceutil/trace.go:171","msg":"trace[914409919] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5704; }","duration":"128.843039ms","start":"2026-10-17T11:01:37.242454Z","end":"2026-10-17T11:01:37.371297Z","steps":["trace[914409919] 'agreement among raft nodes before linearized reading' (duration: 128.723947ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:37.60391Z","caller":"traceutil/trace.go:171","msg":"trace[1464034000] linearizableReadLoop","detail":"{readStateIndex:5916; appliedIndex:5915; }","duration":"155.684394ms","start":"2026-10-17T11:01:37.448194Z","end":"2026-10-17T11:01:37.603878Z","steps":["trace[1464034000] 'read index received' (duration: 155.490481ms)","trace[1464034000] 'applied index is now lower than readState.Index' (duration: 191.993µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:01:37.60431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.083553ms","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-10-17T11:01:37.604368Z","caller":"traceutil/trace.go:171","msg":"trace[2043965511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5706; }","duration":"156.221995ms","start":"2026-10-17T11:01:37.448129Z","end":"2026-10-17T11:01:37.604351Z","steps":["trace[2043965511] 'agreement among raft nodes before linearized reading' (duration: 155.904749ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:37.604433Z","caller":"traceutil/trace.go:171","msg":"trace[516472349] transaction","detail":"{read_only:false; response_revision:5706; number_of_response:1; }","duration":"189.994612ms","start":"2026-10-17T11:01:37.414371Z","end":"2026-10-17T11:01:37.604365Z","steps":["trace[516472349] 'process raft request' (duration: 189.243167ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:37.620986Z","caller":"traceutil/trace.go:171","msg":"trace[1048383441] transaction","detail":"{read_only:false; response_revision:5707; number_of_response:1; }","duration":"167.256273ms","start":"2026-10-17T11:01:37.453681Z","end":"2026-10-17T11:01:37.620937Z","steps":["trace[1048383441] 'process raft request' (duration: 166.752953ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:37.621049Z","caller":"traceutil/trace.go:171","msg":"trace[667722969] transaction","detail":"{read_only:false; response_revision:5708; number_of_response:1; }","duration":"166.63285ms","start":"2026-10-17T11:01:37.454393Z","end":"2026-10-17T11:01:37.621026Z","steps":["trace[667722969] 'process raft request' (duration: 166.156581ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:37.62112Z","caller":"traceutil/trace.go:171","msg":"trace[557143299] transaction","detail":"{read_only:false; response_revision:5709; number_of_response:1; }","duration":"166.54374ms","start":"2026-10-17T11:01:37.454566Z","end":"2026-10-17T11:01:37.62111Z","steps":["trace[557143299] 'process raft request' (duration: 166.03157ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:37.621044Z","caller":"traceutil/trace.go:171","msg":"trace[2068414929] transaction","detail":"{read_only:false; response_revision:5710; number_of_response:1; }","duration":"165.852457ms","start":"2026-10-17T11:01:37.455144Z","end":"2026-10-17T11:01:37.620996Z","steps":["trace[2068414929] 'process raft request' (duration: 165.48871ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:37.621196Z","caller":"traceutil/trace.go:171","msg":"trace[954477363] transaction","detail":"{read_only:false; response_revision:5711; number_of_response:1; }","duration":"154.087214ms","start":"2026-10-17T11:01:37.467089Z","end":"2026-10-17T11:01:37.621177Z","steps":["trace[954477363] 'process raft request' (duration: 153.587735ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:02:00.639825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2953} {"level":"info","ts":"2026-10-17T11:02:00.715765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2953,"took":"74.307351ms","hash":2154649593,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":31866880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T11:02:00.715908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2154649593,"revision":2953,"compact-revision":-1} {"level":"info","ts":"2026-10-17T11:07:00.646917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5875} {"level":"info","ts":"2026-10-17T11:07:00.734234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5875,"took":"85.301774ms","hash":902904491,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":26275840,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-17T11:07:00.734327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":902904491,"revision":5875,"compact-revision":2953} {"level":"info","ts":"2026-10-17T11:07:35.795714Z","caller":"traceutil/trace.go:171","msg":"trace[1145977381] linearizableReadLoop","detail":"{readStateIndex:8735; appliedIndex:8734; }","duration":"161.915293ms","start":"2026-10-17T11:07:35.633774Z","end":"2026-10-17T11:07:35.795689Z","steps":["trace[1145977381] 'read index received' (duration: 161.76363ms)","trace[1145977381] 'applied index is now lower than readState.Index' (duration: 150.343µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:07:35.795762Z","caller":"traceutil/trace.go:171","msg":"trace[787580244] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"240.046512ms","start":"2026-10-17T11:07:35.555685Z","end":"2026-10-17T11:07:35.795732Z","steps":["trace[787580244] 'process raft request' (duration: 239.809727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:07:35.795889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.102878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:07:35.795956Z","caller":"traceutil/trace.go:171","msg":"trace[476118921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8436; }","duration":"162.22613ms","start":"2026-10-17T11:07:35.633715Z","end":"2026-10-17T11:07:35.795941Z","steps":["trace[476118921] 'agreement among raft nodes before linearized reading' (duration: 162.086917ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:07:35.960098Z","caller":"traceutil/trace.go:171","msg":"trace[1847846785] linearizableReadLoop","detail":"{readStateIndex:8736; appliedIndex:8735; }","duration":"163.333721ms","start":"2026-10-17T11:07:35.796745Z","end":"2026-10-17T11:07:35.960079Z","steps":["trace[1847846785] 'read index received' (duration: 111.885471ms)","trace[1847846785] 'applied index is now lower than readState.Index' (duration: 51.43915ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:07:35.96018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.420202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:07:35.960198Z","caller":"traceutil/trace.go:171","msg":"trace[1840497821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8437; }","duration":"163.476484ms","start":"2026-10-17T11:07:35.796715Z","end":"2026-10-17T11:07:35.960191Z","steps":["trace[1840497821] 'agreement among raft nodes before linearized reading' (duration: 163.426893ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:07:35.960326Z","caller":"traceutil/trace.go:171","msg":"trace[1939985891] transaction","detail":"{read_only:false; response_revision:8437; number_of_response:1; }","duration":"311.856851ms","start":"2026-10-17T11:07:35.648463Z","end":"2026-10-17T11:07:35.96032Z","steps":["trace[1939985891] 'process raft request' (duration: 260.161665ms)","trace[1939985891] 'compare' (duration: 51.365939ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:07:35.960384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:07:35.648438Z","time spent":"311.90629ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:07:48.325705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.082802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zhsk5\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-10-17T11:07:48.32579Z","caller":"traceutil/trace.go:171","msg":"trace[1833344244] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zhsk5; range_end:; response_count:1; response_revision:8500; }","duration":"261.236606ms","start":"2026-10-17T11:07:48.064533Z","end":"2026-10-17T11:07:48.32577Z","steps":["trace[1833344244] 'range keys from in-memory index tree' (duration: 260.897148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:07:48.325827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.021064ms","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-10-17T11:07:48.32596Z","caller":"traceutil/trace.go:171","msg":"trace[1015559522] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8500; }","duration":"133.170466ms","start":"2026-10-17T11:07:48.192771Z","end":"2026-10-17T11:07:48.325941Z","steps":["trace[1015559522] 'range keys from in-memory index tree' (duration: 133.004664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:07:48.326031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.972132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:07:48.326149Z","caller":"traceutil/trace.go:171","msg":"trace[204108881] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"257.34781ms","start":"2026-10-17T11:07:48.068781Z","end":"2026-10-17T11:07:48.326128Z","steps":["trace[204108881] 'process raft request' (duration: 61.165044ms)","trace[204108881] 'compare' (duration: 195.522375ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:07:48.344398Z","caller":"traceutil/trace.go:171","msg":"trace[1903052422] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"274.888755ms","start":"2026-10-17T11:07:48.069484Z","end":"2026-10-17T11:07:48.344373Z","steps":["trace[1903052422] 'process raft request' (duration: 274.707652ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:08:17.504898Z","caller":"traceutil/trace.go:171","msg":"trace[2113721766] transaction","detail":"{read_only:false; response_revision:8717; number_of_response:1; }","duration":"260.40229ms","start":"2026-10-17T11:08:17.244477Z","end":"2026-10-17T11:08:17.504879Z","steps":["trace[2113721766] 'process raft request' (duration: 260.290798ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:08:24.603851Z","caller":"traceutil/trace.go:171","msg":"trace[192108557] transaction","detail":"{read_only:false; response_revision:8753; number_of_response:1; }","duration":"140.312532ms","start":"2026-10-17T11:08:24.463511Z","end":"2026-10-17T11:08:24.603824Z","steps":["trace[192108557] 'process raft request' (duration: 106.57088ms)","trace[192108557] 'compare' (duration: 33.550719ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:08:24.749918Z","caller":"traceutil/trace.go:171","msg":"trace[1333677687] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"112.655235ms","start":"2026-10-17T11:08:24.637237Z","end":"2026-10-17T11:08:24.749892Z","steps":["trace[1333677687] 'process raft request' (duration: 83.076051ms)","trace[1333677687] 'compare' (duration: 29.449342ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:08:30.728605Z","caller":"traceutil/trace.go:171","msg":"trace[1380165261] transaction","detail":"{read_only:false; response_revision:8824; number_of_response:1; }","duration":"100.943732ms","start":"2026-10-17T11:08:30.627633Z","end":"2026-10-17T11:08:30.728577Z","steps":["trace[1380165261] 'process raft request' (duration: 100.82816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:08:30.729447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.097655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tn7fg\" ","response":"range_response_count:1 size:4790"} {"level":"info","ts":"2026-10-17T11:08:30.729503Z","caller":"traceutil/trace.go:171","msg":"trace[439347667] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-tn7fg; range_end:; response_count:1; response_revision:8824; }","duration":"102.188667ms","start":"2026-10-17T11:08:30.6273Z","end":"2026-10-17T11:08:30.729488Z","steps":["trace[439347667] 'agreement among raft nodes before linearized reading' (duration: 102.041484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:08:30.730213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.124311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112370"} {"level":"info","ts":"2026-10-17T11:08:30.730255Z","caller":"traceutil/trace.go:171","msg":"trace[1069431351] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8824; }","duration":"107.169222ms","start":"2026-10-17T11:08:30.623075Z","end":"2026-10-17T11:08:30.730244Z","steps":["trace[1069431351] 'agreement among raft nodes before linearized reading' (duration: 106.108211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:08:30.893893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.416486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:229795"} {"level":"info","ts":"2026-10-17T11:08:30.893953Z","caller":"traceutil/trace.go:171","msg":"trace[1796198954] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8824; }","duration":"158.511609ms","start":"2026-10-17T11:08:30.735429Z","end":"2026-10-17T11:08:30.893941Z","steps":["trace[1796198954] 'range keys from in-memory index tree' (duration: 158.11939ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:08:30.901618Z","caller":"traceutil/trace.go:171","msg":"trace[1812617203] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"161.675859ms","start":"2026-10-17T11:08:30.739926Z","end":"2026-10-17T11:08:30.901602Z","steps":["trace[1812617203] 'process raft request' (duration: 161.542777ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:08:31.079707Z","caller":"traceutil/trace.go:171","msg":"trace[702294796] linearizableReadLoop","detail":"{readStateIndex:9141; appliedIndex:9139; }","duration":"302.685145ms","start":"2026-10-17T11:08:30.777001Z","end":"2026-10-17T11:08:31.079686Z","steps":["trace[702294796] 'read index received' (duration: 124.415719ms)","trace[702294796] 'applied index is now lower than readState.Index' (duration: 178.268336ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:08:31.079818Z","caller":"traceutil/trace.go:171","msg":"trace[811410031] transaction","detail":"{read_only:false; response_revision:8826; number_of_response:1; }","duration":"336.427948ms","start":"2026-10-17T11:08:30.743348Z","end":"2026-10-17T11:08:31.079776Z","steps":["trace[811410031] 'process raft request' (duration: 250.50284ms)","trace[811410031] 'compare' (duration: 85.710243ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:08:31.07992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.92158ms","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-10-17T11:08:31.079953Z","caller":"traceutil/trace.go:171","msg":"trace[168203489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8826; }","duration":"303.011852ms","start":"2026-10-17T11:08:30.77693Z","end":"2026-10-17T11:08:31.079942Z","steps":["trace[168203489] 'agreement among raft nodes before linearized reading' (duration: 302.874219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:08:31.079997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:08:30.776911Z","time spent":"303.064352ms","remote":"127.0.0.1:47754","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-10-17T11:08:31.080003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:08:30.743332Z","time spent":"336.566211ms","remote":"127.0.0.1:36750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:08:31.080221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.835028ms","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-10-17T11:08:31.080253Z","caller":"traceutil/trace.go:171","msg":"trace[1535025468] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8826; }","duration":"207.90009ms","start":"2026-10-17T11:08:30.872343Z","end":"2026-10-17T11:08:31.080243Z","steps":["trace[1535025468] 'agreement among raft nodes before linearized reading' (duration: 207.829048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:08:31.080211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.399593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-17T11:08:31.080212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.872333ms","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-10-17T11:08:31.080337Z","caller":"traceutil/trace.go:171","msg":"trace[267723592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8826; }","duration":"267.060247ms","start":"2026-10-17T11:08:30.813259Z","end":"2026-10-17T11:08:31.080319Z","steps":["trace[267723592] 'agreement among raft nodes before linearized reading' (duration: 266.819302ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:08:31.080292Z","caller":"traceutil/trace.go:171","msg":"trace[368001148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8826; }","duration":"141.521186ms","start":"2026-10-17T11:08:30.938755Z","end":"2026-10-17T11:08:31.080276Z","steps":["trace[368001148] 'agreement among raft nodes before linearized reading' (duration: 141.334763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:08:31.080567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.76579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-17T11:08:31.080836Z","caller":"traceutil/trace.go:171","msg":"trace[1061319556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8826; }","duration":"162.175359ms","start":"2026-10-17T11:08:30.918641Z","end":"2026-10-17T11:08:31.080816Z","steps":["trace[1061319556] 'agreement among raft nodes before linearized reading' (duration: 161.512067ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:08:46.513676Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002443.wal"} {"level":"info","ts":"2026-10-17T11:09:54.07518Z","caller":"traceutil/trace.go:171","msg":"trace[1131473772] transaction","detail":"{read_only:false; response_revision:9615; number_of_response:1; }","duration":"190.352344ms","start":"2026-10-17T11:09:53.884796Z","end":"2026-10-17T11:09:54.075149Z","steps":["trace[1131473772] 'process raft request' (duration: 190.096449ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:09:58.658257Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5a9e84e0e0a90b63","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:09:58.664157Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-17T11:09:58.664276Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-17T11:12:00.654158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8078} {"level":"info","ts":"2026-10-17T11:12:00.722818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8078,"took":"66.369432ms","hash":4016011915,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":28782592,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-17T11:12:00.722923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4016011915,"revision":8078,"compact-revision":5875} {"level":"info","ts":"2026-10-17T11:13:38.873899Z","caller":"traceutil/trace.go:171","msg":"trace[417656637] transaction","detail":"{read_only:false; response_revision:11466; number_of_response:1; }","duration":"110.008344ms","start":"2026-10-17T11:13:38.763862Z","end":"2026-10-17T11:13:38.87387Z","steps":["trace[417656637] 'process raft request' (duration: 85.984767ms)","trace[417656637] 'compare' (duration: 23.863313ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:13:39.071546Z","caller":"traceutil/trace.go:171","msg":"trace[1971307402] transaction","detail":"{read_only:false; response_revision:11468; number_of_response:1; }","duration":"103.218184ms","start":"2026-10-17T11:13:38.968301Z","end":"2026-10-17T11:13:39.071519Z","steps":["trace[1971307402] 'process raft request' (duration: 69.30495ms)","trace[1971307402] 'compare' (duration: 33.665051ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:13:39.071902Z","caller":"traceutil/trace.go:171","msg":"trace[43218656] transaction","detail":"{read_only:false; response_revision:11469; number_of_response:1; }","duration":"103.229375ms","start":"2026-10-17T11:13:38.96862Z","end":"2026-10-17T11:13:39.07185Z","steps":["trace[43218656] 'process raft request' (duration: 102.832677ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:17:00.661681Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10519} {"level":"info","ts":"2026-10-17T11:17:00.747364Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10519,"took":"83.080761ms","hash":1130350430,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":27422720,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T11:17:00.747441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1130350430,"revision":10519,"compact-revision":8078} {"level":"info","ts":"2026-10-17T11:19:30.794042Z","caller":"traceutil/trace.go:171","msg":"trace[356762297] transaction","detail":"{read_only:false; response_revision:13866; number_of_response:1; }","duration":"123.924628ms","start":"2026-10-17T11:19:30.670051Z","end":"2026-10-17T11:19:30.793975Z","steps":["trace[356762297] 'process raft request' (duration: 76.449254ms)","trace[356762297] 'compare' (duration: 47.25931ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:19:51.147352Z","caller":"traceutil/trace.go:171","msg":"trace[325994780] transaction","detail":"{read_only:false; response_revision:13976; number_of_response:1; }","duration":"146.668794ms","start":"2026-10-17T11:19:51.000592Z","end":"2026-10-17T11:19:51.147261Z","steps":["trace[325994780] 'process raft request' (duration: 99.39767ms)","trace[325994780] 'compare' (duration: 47.029029ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:19:51.147425Z","caller":"traceutil/trace.go:171","msg":"trace[23598713] linearizableReadLoop","detail":"{readStateIndex:14456; appliedIndex:14455; }","duration":"142.964746ms","start":"2026-10-17T11:19:51.004409Z","end":"2026-10-17T11:19:51.147374Z","steps":["trace[23598713] 'read index received' (duration: 95.538309ms)","trace[23598713] 'applied index is now lower than readState.Index' (duration: 47.423357ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:19:51.147739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.313353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-17T11:19:51.147794Z","caller":"traceutil/trace.go:171","msg":"trace[2066265319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13977; }","duration":"143.402144ms","start":"2026-10-17T11:19:51.004381Z","end":"2026-10-17T11:19:51.147783Z","steps":["trace[2066265319] 'agreement among raft nodes before linearized reading' (duration: 143.201ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:20:08.408365Z","caller":"traceutil/trace.go:171","msg":"trace[2084907712] transaction","detail":"{read_only:false; response_revision:14080; number_of_response:1; }","duration":"172.379237ms","start":"2026-10-17T11:20:08.235957Z","end":"2026-10-17T11:20:08.408336Z","steps":["trace[2084907712] 'process raft request' (duration: 172.211324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:20:18.946796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.79546ms","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-10-17T11:20:18.946911Z","caller":"traceutil/trace.go:171","msg":"trace[2141804917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14253; }","duration":"105.962233ms","start":"2026-10-17T11:20:18.840922Z","end":"2026-10-17T11:20:18.946884Z","steps":["trace[2141804917] 'range keys from in-memory index tree' (duration: 105.037465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:21:44.117548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.442691ms","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-10-17T11:21:44.117644Z","caller":"traceutil/trace.go:171","msg":"trace[1376634216] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15270; }","duration":"162.575024ms","start":"2026-10-17T11:21:43.955048Z","end":"2026-10-17T11:21:44.117623Z","steps":["trace[1376634216] 'range keys from in-memory index tree' (duration: 162.240947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:21:50.126419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.111298ms","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-10-17T11:21:50.126508Z","caller":"traceutil/trace.go:171","msg":"trace[1446206182] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15300; }","duration":"159.24103ms","start":"2026-10-17T11:21:49.96725Z","end":"2026-10-17T11:21:50.126491Z","steps":["trace[1446206182] 'range keys from in-memory index tree' (duration: 159.049208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:21:50.126552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.121439ms","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-10-17T11:21:50.126647Z","caller":"traceutil/trace.go:171","msg":"trace[1619986933] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15300; }","duration":"159.311812ms","start":"2026-10-17T11:21:49.967313Z","end":"2026-10-17T11:21:50.126625Z","steps":["trace[1619986933] 'range keys from in-memory index tree' (duration: 159.047937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:21:50.126818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.340663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:21:50.126857Z","caller":"traceutil/trace.go:171","msg":"trace[1884393117] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15300; }","duration":"159.445755ms","start":"2026-10-17T11:21:49.967401Z","end":"2026-10-17T11:21:50.126847Z","steps":["trace[1884393117] 'range keys from in-memory index tree' (duration: 159.20392ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:00.667647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12771} {"level":"info","ts":"2026-10-17T11:22:00.750368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12771,"took":"79.709152ms","hash":1630648470,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":30715904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:22:00.750444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1630648470,"revision":12771,"compact-revision":10519} {"level":"info","ts":"2026-10-17T11:27:00.675419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15354} {"level":"info","ts":"2026-10-17T11:27:00.782508Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15354,"took":"103.597985ms","hash":3640381010,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":31846400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T11:27:00.782587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3640381010,"revision":15354,"compact-revision":12771} {"level":"warn","ts":"2026-10-17T11:27:35.913346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.991857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T11:27:35.91344Z","caller":"traceutil/trace.go:171","msg":"trace[1167175953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17900; }","duration":"245.12227ms","start":"2026-10-17T11:27:35.668299Z","end":"2026-10-17T11:27:35.913421Z","steps":["trace[1167175953] 'range keys from in-memory index tree' (duration: 244.801604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:27:35.913462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.255441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-17T11:27:35.91353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.207385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-17T11:27:35.913512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.263766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:27:35.913612Z","caller":"traceutil/trace.go:171","msg":"trace[470977752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17900; }","duration":"241.347118ms","start":"2026-10-17T11:27:35.672242Z","end":"2026-10-17T11:27:35.913589Z","steps":["trace[470977752] 'range keys from in-memory index tree' (duration: 241.021822ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:27:35.913646Z","caller":"traceutil/trace.go:171","msg":"trace[87859213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17900; }","duration":"279.46014ms","start":"2026-10-17T11:27:35.634166Z","end":"2026-10-17T11:27:35.913626Z","steps":["trace[87859213] 'range keys from in-memory index tree' (duration: 279.187464ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:27:35.913578Z","caller":"traceutil/trace.go:171","msg":"trace[1773481998] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:17900; }","duration":"217.403834ms","start":"2026-10-17T11:27:35.696145Z","end":"2026-10-17T11:27:35.913549Z","steps":["trace[1773481998] 'count revisions from in-memory index tree' (duration: 217.160419ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:27:43.164671Z","caller":"traceutil/trace.go:171","msg":"trace[1303225620] transaction","detail":"{read_only:false; response_revision:17951; number_of_response:1; }","duration":"154.227515ms","start":"2026-10-17T11:27:43.01042Z","end":"2026-10-17T11:27:43.164648Z","steps":["trace[1303225620] 'process raft request' (duration: 154.099582ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:27:43.198475Z","caller":"traceutil/trace.go:171","msg":"trace[1760890710] transaction","detail":"{read_only:false; response_revision:17952; number_of_response:1; }","duration":"167.173801ms","start":"2026-10-17T11:27:43.031271Z","end":"2026-10-17T11:27:43.198445Z","steps":["trace[1760890710] 'process raft request' (duration: 166.888855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:27:43.598682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.292022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-17T11:27:43.598771Z","caller":"traceutil/trace.go:171","msg":"trace[120458546] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17955; }","duration":"140.489835ms","start":"2026-10-17T11:27:43.458259Z","end":"2026-10-17T11:27:43.598748Z","steps":["trace[120458546] 'range keys from in-memory index tree' (duration: 139.113179ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:27:43.885539Z","caller":"traceutil/trace.go:171","msg":"trace[1672746942] linearizableReadLoop","detail":"{readStateIndex:18566; appliedIndex:18565; }","duration":"165.549719ms","start":"2026-10-17T11:27:43.719969Z","end":"2026-10-17T11:27:43.885519Z","steps":["trace[1672746942] 'read index received' (duration: 103.36419ms)","trace[1672746942] 'applied index is now lower than readState.Index' (duration: 62.184439ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:27:43.885566Z","caller":"traceutil/trace.go:171","msg":"trace[1395566458] transaction","detail":"{read_only:false; response_revision:17957; number_of_response:1; }","duration":"255.627037ms","start":"2026-10-17T11:27:43.629907Z","end":"2026-10-17T11:27:43.885534Z","steps":["trace[1395566458] 'process raft request' (duration: 193.412727ms)","trace[1395566458] 'compare' (duration: 61.985895ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:27:43.885723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.749623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-heat-nodes\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-10-17T11:27:43.885751Z","caller":"traceutil/trace.go:171","msg":"trace[887271834] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-heat-nodes; range_end:; response_count:1; response_revision:17957; }","duration":"165.825295ms","start":"2026-10-17T11:27:43.719916Z","end":"2026-10-17T11:27:43.885742Z","steps":["trace[887271834] 'agreement among raft nodes before linearized reading' (duration: 165.667442ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:27:44.122779Z","caller":"traceutil/trace.go:171","msg":"trace[507336216] linearizableReadLoop","detail":"{readStateIndex:18567; appliedIndex:18566; }","duration":"106.206485ms","start":"2026-10-17T11:27:44.016554Z","end":"2026-10-17T11:27:44.122761Z","steps":["trace[507336216] 'read index received' (duration: 92.369792ms)","trace[507336216] 'applied index is now lower than readState.Index' (duration: 13.835663ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:27:44.12291Z","caller":"traceutil/trace.go:171","msg":"trace[403995956] transaction","detail":"{read_only:false; response_revision:17958; number_of_response:1; }","duration":"135.03012ms","start":"2026-10-17T11:27:43.987807Z","end":"2026-10-17T11:27:44.122837Z","steps":["trace[403995956] 'process raft request' (duration: 121.191378ms)","trace[403995956] 'compare' (duration: 13.665779ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:27:44.123014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.438299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-17T11:27:44.123192Z","caller":"traceutil/trace.go:171","msg":"trace[2061648554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17958; }","duration":"106.650663ms","start":"2026-10-17T11:27:44.016521Z","end":"2026-10-17T11:27:44.123171Z","steps":["trace[2061648554] 'agreement among raft nodes before linearized reading' (duration: 106.325046ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:30:31.293171Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5a9e84e0e0a90b63","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:30:31.299772Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-17T11:30:31.299877Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-17T11:32:00.682792Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17550} {"level":"info","ts":"2026-10-17T11:32:00.785224Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17550,"took":"98.702489ms","hash":35224281,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":34414592,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-17T11:32:00.785401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":35224281,"revision":17550,"compact-revision":15354} {"level":"info","ts":"2026-10-17T11:33:05.367004Z","caller":"traceutil/trace.go:171","msg":"trace[1572759671] transaction","detail":"{read_only:false; response_revision:20421; number_of_response:1; }","duration":"134.432455ms","start":"2026-10-17T11:33:05.232529Z","end":"2026-10-17T11:33:05.366961Z","steps":["trace[1572759671] 'process raft request' (duration: 120.95839ms)","trace[1572759671] 'compare' (duration: 13.014327ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:33:06.059445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.026099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T11:33:06.05955Z","caller":"traceutil/trace.go:171","msg":"trace[860193417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20423; }","duration":"104.149752ms","start":"2026-10-17T11:33:05.955376Z","end":"2026-10-17T11:33:06.059526Z","steps":["trace[860193417] 'range keys from in-memory index tree' (duration: 103.790715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:33:06.059522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.700913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-17T11:33:06.059611Z","caller":"traceutil/trace.go:171","msg":"trace[1835045094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20423; }","duration":"103.860346ms","start":"2026-10-17T11:33:05.955731Z","end":"2026-10-17T11:33:06.059591Z","steps":["trace[1835045094] 'range keys from in-memory index tree' (duration: 103.481279ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:33:06.198095Z","caller":"traceutil/trace.go:171","msg":"trace[1812928750] transaction","detail":"{read_only:false; response_revision:20425; number_of_response:1; }","duration":"133.924986ms","start":"2026-10-17T11:33:06.06415Z","end":"2026-10-17T11:33:06.198075Z","steps":["trace[1812928750] 'process raft request' (duration: 129.136094ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:00.689784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20084} {"level":"info","ts":"2026-10-17T11:37:00.811661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20084,"took":"116.656043ms","hash":1730170924,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":42119168,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-17T11:37:00.811748Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1730170924,"revision":20084,"compact-revision":17550} {"level":"info","ts":"2026-10-17T11:40:07.688752Z","caller":"traceutil/trace.go:171","msg":"trace[662120298] linearizableReadLoop","detail":"{readStateIndex:25583; appliedIndex:25581; }","duration":"114.307339ms","start":"2026-10-17T11:40:07.574418Z","end":"2026-10-17T11:40:07.688725Z","steps":["trace[662120298] 'read index received' (duration: 58.265576ms)","trace[662120298] 'applied index is now lower than readState.Index' (duration: 56.040573ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:40:07.688854Z","caller":"traceutil/trace.go:171","msg":"trace[2080147694] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"129.979086ms","start":"2026-10-17T11:40:07.558843Z","end":"2026-10-17T11:40:07.688822Z","steps":["trace[2080147694] 'process raft request' (duration: 119.61862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:40:07.688923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.489492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:40:07.688983Z","caller":"traceutil/trace.go:171","msg":"trace[1357320803] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24775; }","duration":"114.562583ms","start":"2026-10-17T11:40:07.574404Z","end":"2026-10-17T11:40:07.688967Z","steps":["trace[1357320803] 'agreement among raft nodes before linearized reading' (duration: 114.436821ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:40:09.485615Z","caller":"traceutil/trace.go:171","msg":"trace[991786841] linearizableReadLoop","detail":"{readStateIndex:25597; appliedIndex:25596; }","duration":"155.878467ms","start":"2026-10-17T11:40:09.329699Z","end":"2026-10-17T11:40:09.485577Z","steps":["trace[991786841] 'read index received' (duration: 138.149001ms)","trace[991786841] 'applied index is now lower than readState.Index' (duration: 17.727716ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:40:09.485835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.101932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:40:09.485881Z","caller":"traceutil/trace.go:171","msg":"trace[805572014] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24786; }","duration":"156.221173ms","start":"2026-10-17T11:40:09.329647Z","end":"2026-10-17T11:40:09.485868Z","steps":["trace[805572014] 'agreement among raft nodes before linearized reading' (duration: 156.07804ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:16.955666Z","caller":"traceutil/trace.go:171","msg":"trace[1245384583] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"273.179663ms","start":"2026-10-17T11:41:16.682455Z","end":"2026-10-17T11:41:16.955635Z","steps":["trace[1245384583] 'process raft request' (duration: 273.015009ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:17.064654Z","caller":"traceutil/trace.go:171","msg":"trace[1185143808] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"253.384007ms","start":"2026-10-17T11:41:16.811229Z","end":"2026-10-17T11:41:17.064613Z","steps":["trace[1185143808] 'process raft request' (duration: 251.888879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:17.484363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.202656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:41:17.484846Z","caller":"traceutil/trace.go:171","msg":"trace[275317499] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"415.536144ms","start":"2026-10-17T11:41:17.069277Z","end":"2026-10-17T11:41:17.484814Z","steps":["trace[275317499] 'process raft request' (duration: 143.782348ms)","trace[275317499] 'compare' (duration: 270.815918ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:17.485094Z","caller":"traceutil/trace.go:171","msg":"trace[601699923] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"414.79723ms","start":"2026-10-17T11:41:17.070257Z","end":"2026-10-17T11:41:17.485054Z","steps":["trace[601699923] 'process raft request' (duration: 414.216429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:17.485133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:17.069259Z","time spent":"415.657537ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:41:17.485149Z","caller":"traceutil/trace.go:171","msg":"trace[693513173] linearizableReadLoop","detail":"{readStateIndex:26231; appliedIndex:26229; }","duration":"385.186279ms","start":"2026-10-17T11:41:17.099924Z","end":"2026-10-17T11:41:17.48511Z","steps":["trace[693513173] 'read index received' (duration: 113.040275ms)","trace[693513173] 'applied index is now lower than readState.Index' (duration: 272.133903ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:41:17.485269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:17.070216Z","time spent":"414.959843ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:41:17.485382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.445913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-10-17T11:41:17.485331Z","caller":"traceutil/trace.go:171","msg":"trace[436994066] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"267.72397ms","start":"2026-10-17T11:41:17.217185Z","end":"2026-10-17T11:41:17.484909Z","steps":["trace[436994066] 'process raft request' (duration: 267.654968ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:17.485449Z","caller":"traceutil/trace.go:171","msg":"trace[1761125300] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:25407; }","duration":"385.546616ms","start":"2026-10-17T11:41:17.099883Z","end":"2026-10-17T11:41:17.48543Z","steps":["trace[1761125300] 'agreement among raft nodes before linearized reading' (duration: 385.320041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:17.485487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:17.099866Z","time spent":"385.612947ms","remote":"127.0.0.1:47672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1589,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" "} {"level":"warn","ts":"2026-10-17T11:41:17.768292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.866037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:41:17.76839Z","caller":"traceutil/trace.go:171","msg":"trace[501121635] linearizableReadLoop","detail":"{readStateIndex:26233; appliedIndex:26232; }","duration":"283.156932ms","start":"2026-10-17T11:41:17.485218Z","end":"2026-10-17T11:41:17.768375Z","steps":["trace[501121635] 'read index received' (duration: 175.00977ms)","trace[501121635] 'applied index is now lower than readState.Index' (duration: 108.145572ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:41:17.768472Z","caller":"traceutil/trace.go:171","msg":"trace[2088745273] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"353.193842ms","start":"2026-10-17T11:41:17.415267Z","end":"2026-10-17T11:41:17.768461Z","steps":["trace[2088745273] 'process raft request' (duration: 245.039219ms)","trace[2088745273] 'compare' (duration: 107.137983ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:41:17.768702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.223746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-17T11:41:17.768738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.379549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3cfb1310-060b-4540-8f96-4d08a25b4cb3\" ","response":"range_response_count:1 size:1284"} {"level":"warn","ts":"2026-10-17T11:41:17.768717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.118995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:41:17.768789Z","caller":"traceutil/trace.go:171","msg":"trace[1371593292] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3cfb1310-060b-4540-8f96-4d08a25b4cb3; range_end:; response_count:1; response_revision:25408; }","duration":"280.466251ms","start":"2026-10-17T11:41:17.488309Z","end":"2026-10-17T11:41:17.768775Z","steps":["trace[1371593292] 'agreement among raft nodes before linearized reading' (duration: 280.264948ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:41:17.768803Z","caller":"traceutil/trace.go:171","msg":"trace[1459300950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25408; }","duration":"134.263397ms","start":"2026-10-17T11:41:17.634522Z","end":"2026-10-17T11:41:17.768785Z","steps":["trace[1459300950] 'agreement among raft nodes before linearized reading' (duration: 134.086405ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:17.768932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.728066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:41:17.768793Z","caller":"traceutil/trace.go:171","msg":"trace[1452916715] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25408; }","duration":"593.375898ms","start":"2026-10-17T11:41:17.175395Z","end":"2026-10-17T11:41:17.76877Z","steps":["trace[1452916715] 'agreement among raft nodes before linearized reading' (duration: 593.140974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:17.769006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.765072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:186"} {"level":"info","ts":"2026-10-17T11:41:17.769009Z","caller":"traceutil/trace.go:171","msg":"trace[1972500548] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25408; }","duration":"202.982841ms","start":"2026-10-17T11:41:17.566009Z","end":"2026-10-17T11:41:17.768992Z","steps":["trace[1972500548] 'agreement among raft nodes before linearized reading' (duration: 202.728246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:17.769025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:17.175368Z","time spent":"593.640663ms","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"info","ts":"2026-10-17T11:41:17.769046Z","caller":"traceutil/trace.go:171","msg":"trace[2083362691] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:25408; }","duration":"586.849144ms","start":"2026-10-17T11:41:17.182186Z","end":"2026-10-17T11:41:17.769036Z","steps":["trace[2083362691] 'agreement among raft nodes before linearized reading' (duration: 586.63244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:41:17.769122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:17.182168Z","time spent":"586.942895ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-10-17T11:41:17.769177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:41:17.415198Z","time spent":"353.302633ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:41:43.650231Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067b0.wal"} {"level":"info","ts":"2026-10-17T11:42:00.696591Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22876} {"level":"info","ts":"2026-10-17T11:42:00.839656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22876,"took":"137.363985ms","hash":3717974218,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":37027840,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-17T11:42:00.839731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3717974218,"revision":22876,"compact-revision":20084} {"level":"info","ts":"2026-10-17T11:44:28.794922Z","caller":"traceutil/trace.go:171","msg":"trace[152963638] transaction","detail":"{read_only:false; response_revision:27308; number_of_response:1; }","duration":"112.082517ms","start":"2026-10-17T11:44:28.682818Z","end":"2026-10-17T11:44:28.794901Z","steps":["trace[152963638] 'process raft request' (duration: 70.162631ms)","trace[152963638] 'compare' (duration: 41.766203ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:03.465332Z","caller":"traceutil/trace.go:171","msg":"trace[373067176] transaction","detail":"{read_only:false; response_revision:27693; number_of_response:1; }","duration":"149.897174ms","start":"2026-10-17T11:45:03.315408Z","end":"2026-10-17T11:45:03.465306Z","steps":["trace[373067176] 'process raft request' (duration: 149.829622ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:03.465342Z","caller":"traceutil/trace.go:171","msg":"trace[317108036] transaction","detail":"{read_only:false; response_revision:27692; number_of_response:1; }","duration":"156.789715ms","start":"2026-10-17T11:45:03.30852Z","end":"2026-10-17T11:45:03.465309Z","steps":["trace[317108036] 'process raft request' (duration: 156.673382ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:03.465361Z","caller":"traceutil/trace.go:171","msg":"trace[2072224683] transaction","detail":"{read_only:false; response_revision:27691; number_of_response:1; }","duration":"159.107639ms","start":"2026-10-17T11:45:03.306154Z","end":"2026-10-17T11:45:03.465261Z","steps":["trace[2072224683] 'process raft request' (duration: 125.253706ms)","trace[2072224683] 'compare' (duration: 33.649159ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:47:00.708023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25896} {"level":"info","ts":"2026-10-17T11:47:00.864445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25896,"took":"151.505544ms","hash":2000748715,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":35127296,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-17T11:47:00.86452Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2000748715,"revision":25896,"compact-revision":22876} {"level":"info","ts":"2026-10-17T11:48:08.160191Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5a9e84e0e0a90b63","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:48:08.167387Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-17T11:48:08.167606Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-17T11:52:00.71582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28593} {"level":"info","ts":"2026-10-17T11:52:00.859673Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28593,"took":"139.894774ms","hash":37276092,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":30228480,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T11:52:00.859741Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":37276092,"revision":28593,"compact-revision":25896} {"level":"info","ts":"2026-10-17T11:57:00.725121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30811} {"level":"info","ts":"2026-10-17T11:57:00.842455Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30811,"took":"113.146901ms","hash":4177131853,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:57:00.842508Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4177131853,"revision":30811,"compact-revision":28593} {"level":"info","ts":"2026-10-17T12:02:00.739163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33077} {"level":"info","ts":"2026-10-17T12:02:00.899028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33077,"took":"155.461345ms","hash":4246559656,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":31838208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:02:00.899084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4246559656,"revision":33077,"compact-revision":30811} {"level":"info","ts":"2026-10-17T12:07:00.745814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35465} {"level":"info","ts":"2026-10-17T12:07:00.894165Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35465,"took":"144.239453ms","hash":3613812746,"current-db-size-bytes":59338752,"current-db-size":"59 MB","current-db-size-in-use-bytes":31985664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:07:00.894359Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3613812746,"revision":35465,"compact-revision":33077} {"level":"info","ts":"2026-10-17T12:09:21.095797Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5a9e84e0e0a90b63","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T12:09:21.104128Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-17T12:09:21.104265Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-17T12:12:00.775056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37729} {"level":"info","ts":"2026-10-17T12:12:00.914439Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37729,"took":"135.674189ms","hash":1265966037,"current-db-size-bytes":59760640,"current-db-size":"60 MB","current-db-size-in-use-bytes":32333824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:12:00.914502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1265966037,"revision":37729,"compact-revision":35465}