{"level":"warn","ts":"2026-06-05T06:03:44.548158Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T06:03:44.548273Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-06-05T06:03:44.548364Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T06:03:44.548383Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-06-05T06:03:44.548408Z","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-06-05T06:03:44.549353Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-06-05T06:03:44.549456Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-06-05T06:03:44.552137Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.373068ms"} {"level":"info","ts":"2026-06-05T06:03:44.556607Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-06-05T06:03:44.556707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T06:03:44.55673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-06-05T06:03:44.556742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T06:03:44.556753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-06-05T06:03:44.556794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-06-05T06:03:44.561005Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T06:03:44.563064Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T06:03:44.5647Z","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-06-05T06:03:44.567548Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T06:03:44.567687Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T06:03:44.567793Z","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-06-05T06:03:44.567893Z","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-06-05T06:03:44.567909Z","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-06-05T06:03:44.568698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-06-05T06:03:44.571917Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-06-05T06:03:44.573398Z","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-06-05T06:03:44.573515Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-06-05T06:03:44.573578Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-06-05T06:03:44.573681Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T06:03:44.573726Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T06:03:45.157008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T06:03:45.157087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T06:03:45.157118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-06-05T06:03:45.157144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-06-05T06:03:45.157167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-06-05T06:03:45.157248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-06-05T06:03:45.157273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-06-05T06:03:45.160721Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T06:03:45.160773Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T06:03:45.161268Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T06:03:45.161382Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T06:03:45.162543Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T06:03:45.162674Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T06:03:45.162549Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T06:03:45.162712Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T06:03:45.16273Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T06:03:45.164992Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T06:03:45.170289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-06-05T06:04:32.993451Z","caller":"traceutil/trace.go:171","msg":"trace[803090822] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"205.143347ms","start":"2026-06-05T06:04:32.788265Z","end":"2026-06-05T06:04:32.993408Z","steps":["trace[803090822] 'process raft request' (duration: 199.350752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:33.412659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.608774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T06:04:33.41282Z","caller":"traceutil/trace.go:171","msg":"trace[705462752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"209.8167ms","start":"2026-06-05T06:04:33.202983Z","end":"2026-06-05T06:04:33.4128Z","steps":["trace[705462752] 'range keys from in-memory index tree' (duration: 209.43083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:33.412717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.954146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-05T06:04:33.412925Z","caller":"traceutil/trace.go:171","msg":"trace[527005755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"203.198552ms","start":"2026-06-05T06:04:33.209708Z","end":"2026-06-05T06:04:33.412907Z","steps":["trace[527005755] 'range keys from in-memory index tree' (duration: 202.808331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:33.972023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.913381ms","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-06-05T06:04:33.972143Z","caller":"traceutil/trace.go:171","msg":"trace[1807491479] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:648; }","duration":"405.050806ms","start":"2026-06-05T06:04:33.567074Z","end":"2026-06-05T06:04:33.972125Z","steps":["trace[1807491479] 'range keys from in-memory index tree' (duration: 404.89953ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:04:33.972297Z","caller":"traceutil/trace.go:171","msg":"trace[1915656474] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"547.413563ms","start":"2026-06-05T06:04:33.424829Z","end":"2026-06-05T06:04:33.972243Z","steps":["trace[1915656474] 'process raft request' (duration: 534.162985ms)","trace[1915656474] 'compare' (duration: 12.814405ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:04:33.973036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:04:33.424816Z","time spent":"547.64262ms","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T06:04:33.989873Z","caller":"traceutil/trace.go:171","msg":"trace[934820130] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"495.617277ms","start":"2026-06-05T06:04:33.494103Z","end":"2026-06-05T06:04:33.989721Z","steps":["trace[934820130] 'process raft request' (duration: 494.983309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:33.990196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:04:33.494076Z","time spent":"495.907984ms","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T06:04:34.001036Z","caller":"traceutil/trace.go:171","msg":"trace[2055465911] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"396.851802ms","start":"2026-06-05T06:04:33.604159Z","end":"2026-06-05T06:04:34.001011Z","steps":["trace[2055465911] 'read index received' (duration: 354.927407ms)","trace[2055465911] 'applied index is now lower than readState.Index' (duration: 41.922905ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:04:34.001421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.232932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T06:04:34.001531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.605205ms","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-06-05T06:04:34.001736Z","caller":"traceutil/trace.go:171","msg":"trace[1442859504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"202.842091ms","start":"2026-06-05T06:04:33.798871Z","end":"2026-06-05T06:04:34.001713Z","steps":["trace[1442859504] 'agreement among raft nodes before linearized reading' (duration: 202.531233ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:04:34.001623Z","caller":"traceutil/trace.go:171","msg":"trace[1084260716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"397.481399ms","start":"2026-06-05T06:04:33.604118Z","end":"2026-06-05T06:04:34.0016Z","steps":["trace[1084260716] 'agreement among raft nodes before linearized reading' (duration: 397.207641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:34.001958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:04:33.604099Z","time spent":"397.8364ms","remote":"127.0.0.1:48146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T06:04:43.722215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422988892287546,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T06:04:44.223146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422988892287546,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T06:04:44.268188Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.123013119s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T06:04:44.268464Z","caller":"traceutil/trace.go:171","msg":"trace[1806571157] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"1.123386009s","start":"2026-06-05T06:04:43.14506Z","end":"2026-06-05T06:04:44.268446Z","steps":["trace[1806571157] 'process raft request' (duration: 1.123275386s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:44.268739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:04:43.14504Z","time spent":"1.123638026s","remote":"127.0.0.1:48420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T06:04:44.315854Z","caller":"traceutil/trace.go:171","msg":"trace[1232679803] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"1.094406524s","start":"2026-06-05T06:04:43.221423Z","end":"2026-06-05T06:04:44.315829Z","steps":["trace[1232679803] 'read index received' (duration: 1.04691949s)","trace[1232679803] 'applied index is now lower than readState.Index' (duration: 47.485164ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:04:44.316125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094688392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sljnm\" ","response":"range_response_count:1 size:18709"} {"level":"info","ts":"2026-06-05T06:04:44.316195Z","caller":"traceutil/trace.go:171","msg":"trace[1060253276] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sljnm; range_end:; response_count:1; response_revision:758; }","duration":"1.094809345s","start":"2026-06-05T06:04:43.221368Z","end":"2026-06-05T06:04:44.316178Z","steps":["trace[1060253276] 'agreement among raft nodes before linearized reading' (duration: 1.094607279s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:44.316262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:04:43.221339Z","time spent":"1.094907768s","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18732,"request content":"key:\"/registry/pods/kube-system/cilium-sljnm\" "} {"level":"warn","ts":"2026-06-05T06:04:44.316312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.873572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T06:04:44.316388Z","caller":"traceutil/trace.go:171","msg":"trace[992226638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"270.982494ms","start":"2026-06-05T06:04:44.045385Z","end":"2026-06-05T06:04:44.316367Z","steps":["trace[992226638] 'agreement among raft nodes before linearized reading' (duration: 270.846181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:44.316179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.623796ms","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-06-05T06:04:44.316643Z","caller":"traceutil/trace.go:171","msg":"trace[948059853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"857.10835ms","start":"2026-06-05T06:04:43.45949Z","end":"2026-06-05T06:04:44.316598Z","steps":["trace[948059853] 'agreement among raft nodes before linearized reading' (duration: 856.509343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:44.316704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.277515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T06:04:44.316783Z","caller":"traceutil/trace.go:171","msg":"trace[571906297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"836.366908ms","start":"2026-06-05T06:04:43.480394Z","end":"2026-06-05T06:04:44.316761Z","steps":["trace[571906297] 'agreement among raft nodes before linearized reading' (duration: 836.184873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:44.316849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:04:43.480334Z","time spent":"836.499862ms","remote":"127.0.0.1:48420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T06:04:44.316858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.699654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:04:44.31692Z","caller":"traceutil/trace.go:171","msg":"trace[1726896818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"712.794127ms","start":"2026-06-05T06:04:43.60411Z","end":"2026-06-05T06:04:44.316904Z","steps":["trace[1726896818] 'agreement among raft nodes before linearized reading' (duration: 712.708534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:44.31701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:04:43.604092Z","time spent":"712.900499ms","remote":"127.0.0.1:48146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T06:04:44.316715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:04:43.459472Z","time spent":"857.222294ms","remote":"127.0.0.1:48420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T06:04:49.794566Z","caller":"traceutil/trace.go:171","msg":"trace[1114447428] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"189.722578ms","start":"2026-06-05T06:04:49.604816Z","end":"2026-06-05T06:04:49.794539Z","steps":["trace[1114447428] 'read index received' (duration: 189.475291ms)","trace[1114447428] 'applied index is now lower than readState.Index' (duration: 246.457µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:04:49.79463Z","caller":"traceutil/trace.go:171","msg":"trace[734656085] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"301.760362ms","start":"2026-06-05T06:04:49.492831Z","end":"2026-06-05T06:04:49.794591Z","steps":["trace[734656085] 'process raft request' (duration: 301.546306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:49.794691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.867772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:04:49.794724Z","caller":"traceutil/trace.go:171","msg":"trace[460490483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"189.970535ms","start":"2026-06-05T06:04:49.604744Z","end":"2026-06-05T06:04:49.794714Z","steps":["trace[460490483] 'agreement among raft nodes before linearized reading' (duration: 189.895303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:04:49.794791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:04:49.492817Z","time spent":"301.888395ms","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T06:06:06.014631Z","caller":"traceutil/trace.go:171","msg":"trace[138688383] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"152.846898ms","start":"2026-06-05T06:06:05.861766Z","end":"2026-06-05T06:06:06.014613Z","steps":["trace[138688383] 'read index received' (duration: 152.757255ms)","trace[138688383] 'applied index is now lower than readState.Index' (duration: 85.093µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:06:06.014751Z","caller":"traceutil/trace.go:171","msg":"trace[802965552] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"198.062572ms","start":"2026-06-05T06:06:05.816679Z","end":"2026-06-05T06:06:06.014742Z","steps":["trace[802965552] 'process raft request' (duration: 197.814805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:06:06.01495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.164027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T06:06:06.015001Z","caller":"traceutil/trace.go:171","msg":"trace[1508320773] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1879; }","duration":"153.256209ms","start":"2026-06-05T06:06:05.861725Z","end":"2026-06-05T06:06:06.014981Z","steps":["trace[1508320773] 'agreement among raft nodes before linearized reading' (duration: 153.124425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:06:06.015162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.968833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9f4480cf-97a6-4f69-b7b6-9fccee182f8d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-05T06:06:06.015244Z","caller":"traceutil/trace.go:171","msg":"trace[542324364] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9f4480cf-97a6-4f69-b7b6-9fccee182f8d; range_end:; response_count:1; response_revision:1879; }","duration":"152.066615ms","start":"2026-06-05T06:06:05.863165Z","end":"2026-06-05T06:06:06.015232Z","steps":["trace[542324364] 'agreement among raft nodes before linearized reading' (duration: 151.901561ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:06:23.296875Z","caller":"traceutil/trace.go:171","msg":"trace[1871991348] linearizableReadLoop","detail":"{readStateIndex:2102; appliedIndex:2101; }","duration":"154.145564ms","start":"2026-06-05T06:06:23.142711Z","end":"2026-06-05T06:06:23.296856Z","steps":["trace[1871991348] 'read index received' (duration: 154.01239ms)","trace[1871991348] 'applied index is now lower than readState.Index' (duration: 132.354µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:06:23.297042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.323838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T06:06:23.297075Z","caller":"traceutil/trace.go:171","msg":"trace[1221917982] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2027; }","duration":"154.40121ms","start":"2026-06-05T06:06:23.142661Z","end":"2026-06-05T06:06:23.297062Z","steps":["trace[1221917982] 'agreement among raft nodes before linearized reading' (duration: 154.280367ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:06:23.297075Z","caller":"traceutil/trace.go:171","msg":"trace[693325003] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"171.000406ms","start":"2026-06-05T06:06:23.125993Z","end":"2026-06-05T06:06:23.296994Z","steps":["trace[693325003] 'process raft request' (duration: 170.741619ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:07:03.07931Z","caller":"traceutil/trace.go:171","msg":"trace[1271359656] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"128.643826ms","start":"2026-06-05T06:07:02.950633Z","end":"2026-06-05T06:07:03.079276Z","steps":["trace[1271359656] 'process raft request' (duration: 128.391359ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:07:03.279533Z","caller":"traceutil/trace.go:171","msg":"trace[1907567030] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"195.835141ms","start":"2026-06-05T06:07:03.08363Z","end":"2026-06-05T06:07:03.279465Z","steps":["trace[1907567030] 'process raft request' (duration: 134.271097ms)","trace[1907567030] 'compare' (duration: 61.315137ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:07:03.279551Z","caller":"traceutil/trace.go:171","msg":"trace[1539914022] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2331; }","duration":"132.798995ms","start":"2026-06-05T06:07:03.14655Z","end":"2026-06-05T06:07:03.279349Z","steps":["trace[1539914022] 'read index received' (duration: 71.303883ms)","trace[1539914022] 'applied index is now lower than readState.Index' (duration: 61.494162ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:07:03.279802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.231437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T06:07:03.279878Z","caller":"traceutil/trace.go:171","msg":"trace[1420172872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2248; }","duration":"133.315949ms","start":"2026-06-05T06:07:03.14654Z","end":"2026-06-05T06:07:03.279856Z","steps":["trace[1420172872] 'agreement among raft nodes before linearized reading' (duration: 133.095873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:07:03.280238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.399304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-05T06:07:03.28025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.722888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T06:07:03.28033Z","caller":"traceutil/trace.go:171","msg":"trace[1843743137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2248; }","duration":"132.530197ms","start":"2026-06-05T06:07:03.147771Z","end":"2026-06-05T06:07:03.280301Z","steps":["trace[1843743137] 'agreement among raft nodes before linearized reading' (duration: 131.990582ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:07:03.280356Z","caller":"traceutil/trace.go:171","msg":"trace[298393087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2248; }","duration":"121.869662ms","start":"2026-06-05T06:07:03.158459Z","end":"2026-06-05T06:07:03.280329Z","steps":["trace[298393087] 'agreement among raft nodes before linearized reading' (duration: 121.311136ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:07:03.418385Z","caller":"traceutil/trace.go:171","msg":"trace[1775675677] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"133.63612ms","start":"2026-06-05T06:07:03.284726Z","end":"2026-06-05T06:07:03.418362Z","steps":["trace[1775675677] 'process raft request' (duration: 133.555918ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:07:03.418406Z","caller":"traceutil/trace.go:171","msg":"trace[972870181] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"133.925288ms","start":"2026-06-05T06:07:03.284447Z","end":"2026-06-05T06:07:03.418372Z","steps":["trace[972870181] 'process raft request' (duration: 129.0833ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:07:03.418779Z","caller":"traceutil/trace.go:171","msg":"trace[1713995213] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"134.00269ms","start":"2026-06-05T06:07:03.284746Z","end":"2026-06-05T06:07:03.418749Z","steps":["trace[1713995213] 'process raft request' (duration: 133.581798ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:08:33.991065Z","caller":"traceutil/trace.go:171","msg":"trace[127376445] transaction","detail":"{read_only:false; response_revision:2926; number_of_response:1; }","duration":"140.698401ms","start":"2026-06-05T06:08:33.850341Z","end":"2026-06-05T06:08:33.991039Z","steps":["trace[127376445] 'process raft request' (duration: 140.539936ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:08:34.001348Z","caller":"traceutil/trace.go:171","msg":"trace[1618117120] linearizableReadLoop","detail":"{readStateIndex:3039; appliedIndex:3037; }","duration":"138.606632ms","start":"2026-06-05T06:08:33.862716Z","end":"2026-06-05T06:08:34.001322Z","steps":["trace[1618117120] 'read index received' (duration: 128.143333ms)","trace[1618117120] 'applied index is now lower than readState.Index' (duration: 10.462199ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:08:34.00142Z","caller":"traceutil/trace.go:171","msg":"trace[1684726331] transaction","detail":"{read_only:false; response_revision:2927; number_of_response:1; }","duration":"150.493921ms","start":"2026-06-05T06:08:33.850908Z","end":"2026-06-05T06:08:34.001402Z","steps":["trace[1684726331] 'process raft request' (duration: 150.296386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:08:34.001653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.916041ms","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-06-05T06:08:34.001694Z","caller":"traceutil/trace.go:171","msg":"trace[2039580159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2927; }","duration":"138.972552ms","start":"2026-06-05T06:08:33.862711Z","end":"2026-06-05T06:08:34.001684Z","steps":["trace[2039580159] 'agreement among raft nodes before linearized reading' (duration: 138.700965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:08:34.185536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.80535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-05T06:08:34.185634Z","caller":"traceutil/trace.go:171","msg":"trace[1701169284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2928; }","duration":"142.966457ms","start":"2026-06-05T06:08:34.042648Z","end":"2026-06-05T06:08:34.185614Z","steps":["trace[1701169284] 'range keys from in-memory index tree' (duration: 142.575193ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:08:34.32707Z","caller":"traceutil/trace.go:171","msg":"trace[1092382290] transaction","detail":"{read_only:false; response_revision:2929; number_of_response:1; }","duration":"136.77745ms","start":"2026-06-05T06:08:34.190257Z","end":"2026-06-05T06:08:34.327034Z","steps":["trace[1092382290] 'process raft request' (duration: 136.574644ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:12:32.441901Z","caller":"traceutil/trace.go:171","msg":"trace[283452682] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"111.665995ms","start":"2026-06-05T06:12:32.330218Z","end":"2026-06-05T06:12:32.441884Z","steps":["trace[283452682] 'process raft request' (duration: 111.570022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:12:36.361896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.396756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T06:12:36.362Z","caller":"traceutil/trace.go:171","msg":"trace[880867692] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5146; }","duration":"148.54301ms","start":"2026-06-05T06:12:36.213435Z","end":"2026-06-05T06:12:36.361978Z","steps":["trace[880867692] 'count revisions from in-memory index tree' (duration: 148.324454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:12:36.36232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.819603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T06:12:36.362425Z","caller":"traceutil/trace.go:171","msg":"trace[1103016108] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:5146; }","duration":"108.981178ms","start":"2026-06-05T06:12:36.253422Z","end":"2026-06-05T06:12:36.362404Z","steps":["trace[1103016108] 'count revisions from in-memory index tree' (duration: 108.746212ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:12:36.718218Z","caller":"traceutil/trace.go:171","msg":"trace[1251793173] linearizableReadLoop","detail":"{readStateIndex:5346; appliedIndex:5345; }","duration":"114.406102ms","start":"2026-06-05T06:12:36.60379Z","end":"2026-06-05T06:12:36.718196Z","steps":["trace[1251793173] 'read index received' (duration: 29.114863ms)","trace[1251793173] 'applied index is now lower than readState.Index' (duration: 85.290459ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:12:36.718338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.529166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:12:36.718363Z","caller":"traceutil/trace.go:171","msg":"trace[513891937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5151; }","duration":"114.592019ms","start":"2026-06-05T06:12:36.603763Z","end":"2026-06-05T06:12:36.718355Z","steps":["trace[513891937] 'agreement among raft nodes before linearized reading' (duration: 114.514727ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:12:36.718664Z","caller":"traceutil/trace.go:171","msg":"trace[1501699924] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"135.755553ms","start":"2026-06-05T06:12:36.582829Z","end":"2026-06-05T06:12:36.718585Z","steps":["trace[1501699924] 'process raft request' (duration: 50.126664ms)","trace[1501699924] 'compare' (duration: 85.106825ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:12:36.908979Z","caller":"traceutil/trace.go:171","msg":"trace[2062975788] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"128.780635ms","start":"2026-06-05T06:12:36.780179Z","end":"2026-06-05T06:12:36.90896Z","steps":["trace[2062975788] 'process raft request' (duration: 77.971541ms)","trace[2062975788] 'compare' (duration: 50.702511ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:12:57.072076Z","caller":"traceutil/trace.go:171","msg":"trace[1590108203] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"118.827025ms","start":"2026-06-05T06:12:56.953223Z","end":"2026-06-05T06:12:57.07205Z","steps":["trace[1590108203] 'process raft request' (duration: 118.62734ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:13:10.286552Z","caller":"traceutil/trace.go:171","msg":"trace[775525809] transaction","detail":"{read_only:false; response_revision:5717; number_of_response:1; }","duration":"113.940846ms","start":"2026-06-05T06:13:10.172547Z","end":"2026-06-05T06:13:10.286487Z","steps":["trace[775525809] 'process raft request' (duration: 59.62473ms)","trace[775525809] 'compare' (duration: 53.954306ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:13:10.286608Z","caller":"traceutil/trace.go:171","msg":"trace[31711987] transaction","detail":"{read_only:false; response_revision:5719; number_of_response:1; }","duration":"113.40137ms","start":"2026-06-05T06:13:10.173195Z","end":"2026-06-05T06:13:10.286596Z","steps":["trace[31711987] 'process raft request' (duration: 113.178904ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:13:10.286604Z","caller":"traceutil/trace.go:171","msg":"trace[2014970629] transaction","detail":"{read_only:false; response_revision:5718; number_of_response:1; }","duration":"113.847274ms","start":"2026-06-05T06:13:10.172732Z","end":"2026-06-05T06:13:10.286579Z","steps":["trace[2014970629] 'process raft request' (duration: 113.556846ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:13:45.274536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2989} {"level":"info","ts":"2026-06-05T06:13:45.355533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2989,"took":"79.377876ms","hash":3125714083,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":33640448,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-05T06:13:45.355656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3125714083,"revision":2989,"compact-revision":-1} {"level":"info","ts":"2026-06-05T06:15:37.302186Z","caller":"traceutil/trace.go:171","msg":"trace[628575802] linearizableReadLoop","detail":"{readStateIndex:6996; appliedIndex:6995; }","duration":"108.820809ms","start":"2026-06-05T06:15:37.193348Z","end":"2026-06-05T06:15:37.302169Z","steps":["trace[628575802] 'read index received' (duration: 108.685525ms)","trace[628575802] 'applied index is now lower than readState.Index' (duration: 134.634µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:15:37.302311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.942522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:15:37.302331Z","caller":"traceutil/trace.go:171","msg":"trace[1717153356] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:6752; }","duration":"109.008234ms","start":"2026-06-05T06:15:37.193317Z","end":"2026-06-05T06:15:37.302325Z","steps":["trace[1717153356] 'agreement among raft nodes before linearized reading' (duration: 108.930552ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:15:37.302575Z","caller":"traceutil/trace.go:171","msg":"trace[24069748] transaction","detail":"{read_only:false; response_revision:6752; number_of_response:1; }","duration":"170.042694ms","start":"2026-06-05T06:15:37.132526Z","end":"2026-06-05T06:15:37.302568Z","steps":["trace[24069748] 'process raft request' (duration: 169.518629ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:15:38.074479Z","caller":"traceutil/trace.go:171","msg":"trace[2053579170] transaction","detail":"{read_only:false; response_revision:6753; number_of_response:1; }","duration":"209.141984ms","start":"2026-06-05T06:15:37.865261Z","end":"2026-06-05T06:15:38.074403Z","steps":["trace[2053579170] 'process raft request' (duration: 208.793484ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:18:45.279958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6035} {"level":"info","ts":"2026-06-05T06:18:45.372813Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6035,"took":"90.954125ms","hash":1509582815,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":21909504,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-05T06:18:45.372888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1509582815,"revision":6035,"compact-revision":2989} {"level":"info","ts":"2026-06-05T06:19:14.614666Z","caller":"traceutil/trace.go:171","msg":"trace[1169496516] linearizableReadLoop","detail":"{readStateIndex:8746; appliedIndex:8745; }","duration":"147.448515ms","start":"2026-06-05T06:19:14.4672Z","end":"2026-06-05T06:19:14.614648Z","steps":["trace[1169496516] 'read index received' (duration: 147.187108ms)","trace[1169496516] 'applied index is now lower than readState.Index' (duration: 260.617µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:19:14.61474Z","caller":"traceutil/trace.go:171","msg":"trace[330142741] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"149.36985ms","start":"2026-06-05T06:19:14.465321Z","end":"2026-06-05T06:19:14.614691Z","steps":["trace[330142741] 'process raft request' (duration: 149.188285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:19:14.614805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.586069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:19:14.614832Z","caller":"traceutil/trace.go:171","msg":"trace[44596285] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8447; }","duration":"147.659251ms","start":"2026-06-05T06:19:14.467164Z","end":"2026-06-05T06:19:14.614824Z","steps":["trace[44596285] 'agreement among raft nodes before linearized reading' (duration: 147.600419ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:19:43.116412Z","caller":"traceutil/trace.go:171","msg":"trace[162943509] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"155.274657ms","start":"2026-06-05T06:19:42.961114Z","end":"2026-06-05T06:19:43.116389Z","steps":["trace[162943509] 'process raft request' (duration: 155.131203ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:19:55.744165Z","caller":"traceutil/trace.go:171","msg":"trace[1240163826] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"125.498643ms","start":"2026-06-05T06:19:55.618638Z","end":"2026-06-05T06:19:55.744136Z","steps":["trace[1240163826] 'process raft request' (duration: 125.349169ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:19:55.747614Z","caller":"traceutil/trace.go:171","msg":"trace[1736631374] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"128.952982ms","start":"2026-06-05T06:19:55.618637Z","end":"2026-06-05T06:19:55.74759Z","steps":["trace[1736631374] 'process raft request' (duration: 128.762876ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:19:55.747819Z","caller":"traceutil/trace.go:171","msg":"trace[1630453853] linearizableReadLoop","detail":"{readStateIndex:9076; appliedIndex:9073; }","duration":"127.362987ms","start":"2026-06-05T06:19:55.620419Z","end":"2026-06-05T06:19:55.747782Z","steps":["trace[1630453853] 'read index received' (duration: 123.59436ms)","trace[1630453853] 'applied index is now lower than readState.Index' (duration: 3.766897ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:19:55.747909Z","caller":"traceutil/trace.go:171","msg":"trace[1695713448] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"128.048937ms","start":"2026-06-05T06:19:55.619817Z","end":"2026-06-05T06:19:55.747866Z","steps":["trace[1695713448] 'process raft request' (duration: 127.723757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:19:55.748168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.717437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-05T06:19:55.748407Z","caller":"traceutil/trace.go:171","msg":"trace[162319367] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8765; }","duration":"127.914653ms","start":"2026-06-05T06:19:55.620396Z","end":"2026-06-05T06:19:55.748311Z","steps":["trace[162319367] 'agreement among raft nodes before linearized reading' (duration: 127.541203ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:20:09.932878Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f1.wal"} {"level":"info","ts":"2026-06-05T06:21:30.547209Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ba52c55cf4193752","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-05T06:21:30.552462Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-05T06:21:30.552539Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-05T06:23:45.287233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8155} {"level":"info","ts":"2026-06-05T06:23:45.346251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8155,"took":"57.478426ms","hash":581353978,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":29696000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-05T06:23:45.346332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":581353978,"revision":8155,"compact-revision":6035} {"level":"info","ts":"2026-06-05T06:24:48.603402Z","caller":"traceutil/trace.go:171","msg":"trace[1307677069] linearizableReadLoop","detail":"{readStateIndex:11707; appliedIndex:11706; }","duration":"116.726911ms","start":"2026-06-05T06:24:48.486648Z","end":"2026-06-05T06:24:48.603375Z","steps":["trace[1307677069] 'read index received' (duration: 58.569917ms)","trace[1307677069] 'applied index is now lower than readState.Index' (duration: 58.156064ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:24:48.603465Z","caller":"traceutil/trace.go:171","msg":"trace[1738142675] transaction","detail":"{read_only:false; response_revision:11325; number_of_response:1; }","duration":"149.452828ms","start":"2026-06-05T06:24:48.45396Z","end":"2026-06-05T06:24:48.603413Z","steps":["trace[1738142675] 'process raft request' (duration: 91.19245ms)","trace[1738142675] 'compare' (duration: 58.094333ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:24:48.603635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.969968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:24:48.603675Z","caller":"traceutil/trace.go:171","msg":"trace[2114317194] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11325; }","duration":"117.06669ms","start":"2026-06-05T06:24:48.486597Z","end":"2026-06-05T06:24:48.603664Z","steps":["trace[2114317194] 'agreement among raft nodes before linearized reading' (duration: 116.921556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:24:48.603893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.079481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T06:24:48.603963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.139422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:24:48.603999Z","caller":"traceutil/trace.go:171","msg":"trace[618213136] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11325; }","duration":"117.197304ms","start":"2026-06-05T06:24:48.486789Z","end":"2026-06-05T06:24:48.603986Z","steps":["trace[618213136] 'agreement among raft nodes before linearized reading' (duration: 117.144833ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:24:48.603992Z","caller":"traceutil/trace.go:171","msg":"trace[203298467] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11325; }","duration":"117.224905ms","start":"2026-06-05T06:24:48.486748Z","end":"2026-06-05T06:24:48.603973Z","steps":["trace[203298467] 'agreement among raft nodes before linearized reading' (duration: 117.06281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:24:48.604012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.093671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:24:48.604096Z","caller":"traceutil/trace.go:171","msg":"trace[2083511579] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11325; }","duration":"117.207606ms","start":"2026-06-05T06:24:48.486869Z","end":"2026-06-05T06:24:48.604077Z","steps":["trace[2083511579] 'agreement among raft nodes before linearized reading' (duration: 117.057941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:24:48.604109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.166888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-05T06:24:48.604159Z","caller":"traceutil/trace.go:171","msg":"trace[653431233] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11325; }","duration":"115.24318ms","start":"2026-06-05T06:24:48.488901Z","end":"2026-06-05T06:24:48.604144Z","steps":["trace[653431233] 'agreement among raft nodes before linearized reading' (duration: 115.059784ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:24:49.419229Z","caller":"traceutil/trace.go:171","msg":"trace[1167356795] linearizableReadLoop","detail":"{readStateIndex:11713; appliedIndex:11712; }","duration":"140.635196ms","start":"2026-06-05T06:24:49.278549Z","end":"2026-06-05T06:24:49.419184Z","steps":["trace[1167356795] 'read index received' (duration: 59.315088ms)","trace[1167356795] 'applied index is now lower than readState.Index' (duration: 81.318728ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:24:49.419391Z","caller":"traceutil/trace.go:171","msg":"trace[1293871374] transaction","detail":"{read_only:false; response_revision:11331; number_of_response:1; }","duration":"188.719414ms","start":"2026-06-05T06:24:49.230635Z","end":"2026-06-05T06:24:49.419354Z","steps":["trace[1293871374] 'process raft request' (duration: 107.286892ms)","trace[1293871374] 'compare' (duration: 81.013429ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:24:49.42009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.523432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-05T06:24:49.420816Z","caller":"traceutil/trace.go:171","msg":"trace[1981563592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11331; }","duration":"141.728678ms","start":"2026-06-05T06:24:49.278472Z","end":"2026-06-05T06:24:49.420201Z","steps":["trace[1981563592] 'agreement among raft nodes before linearized reading' (duration: 140.849713ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:26:14.728328Z","caller":"traceutil/trace.go:171","msg":"trace[940333349] linearizableReadLoop","detail":"{readStateIndex:12286; appliedIndex:12285; }","duration":"379.485014ms","start":"2026-06-05T06:26:14.348813Z","end":"2026-06-05T06:26:14.728298Z","steps":["trace[940333349] 'read index received' (duration: 379.416242ms)","trace[940333349] 'applied index is now lower than readState.Index' (duration: 67.382µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:26:14.728443Z","caller":"traceutil/trace.go:171","msg":"trace[144943133] transaction","detail":"{read_only:false; response_revision:11886; number_of_response:1; }","duration":"427.9014ms","start":"2026-06-05T06:26:14.300505Z","end":"2026-06-05T06:26:14.728406Z","steps":["trace[144943133] 'process raft request' (duration: 427.634023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:26:14.728635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:26:14.300462Z","time spent":"428.071056ms","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T06:26:14.728755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.927236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-05T06:26:14.728805Z","caller":"traceutil/trace.go:171","msg":"trace[957725107] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11886; }","duration":"380.040159ms","start":"2026-06-05T06:26:14.348753Z","end":"2026-06-05T06:26:14.728793Z","steps":["trace[957725107] 'agreement among raft nodes before linearized reading' (duration: 379.70364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:26:14.728842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:26:14.348729Z","time spent":"380.104011ms","remote":"127.0.0.1:48296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/cinder-api\" "} {"level":"warn","ts":"2026-06-05T06:26:14.732388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.313963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T06:26:14.732464Z","caller":"traceutil/trace.go:171","msg":"trace[268642998] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11888; }","duration":"167.443787ms","start":"2026-06-05T06:26:14.565002Z","end":"2026-06-05T06:26:14.732446Z","steps":["trace[268642998] 'agreement among raft nodes before linearized reading' (duration: 167.305923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:26:14.7325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.970633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:26:14.732586Z","caller":"traceutil/trace.go:171","msg":"trace[1842677988] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11888; }","duration":"383.127917ms","start":"2026-06-05T06:26:14.349437Z","end":"2026-06-05T06:26:14.732565Z","steps":["trace[1842677988] 'agreement among raft nodes before linearized reading' (duration: 382.993713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:26:14.7324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.108527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:1 size:7349"} {"level":"warn","ts":"2026-06-05T06:26:14.732652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:26:14.349406Z","time spent":"383.23126ms","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-06-05T06:26:14.732629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.678116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:26:14.732729Z","caller":"traceutil/trace.go:171","msg":"trace[1500973051] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:1; response_revision:11888; }","duration":"383.449546ms","start":"2026-06-05T06:26:14.349231Z","end":"2026-06-05T06:26:14.73268Z","steps":["trace[1500973051] 'agreement among raft nodes before linearized reading' (duration: 382.955452ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:26:14.732743Z","caller":"traceutil/trace.go:171","msg":"trace[71656843] transaction","detail":"{read_only:false; response_revision:11887; number_of_response:1; }","duration":"107.84422ms","start":"2026-06-05T06:26:14.624779Z","end":"2026-06-05T06:26:14.732623Z","steps":["trace[71656843] 'process raft request' (duration: 107.236033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:26:14.732854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T06:26:14.349214Z","time spent":"383.625842ms","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":7372,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"info","ts":"2026-06-05T06:26:14.73274Z","caller":"traceutil/trace.go:171","msg":"trace[753742080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11888; }","duration":"128.81656ms","start":"2026-06-05T06:26:14.603894Z","end":"2026-06-05T06:26:14.732711Z","steps":["trace[753742080] 'agreement among raft nodes before linearized reading' (duration: 128.624535ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:28:45.293607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10755} {"level":"info","ts":"2026-06-05T06:28:45.375014Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10755,"took":"78.802289ms","hash":4036721767,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":28041216,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-05T06:28:45.375095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4036721767,"revision":10755,"compact-revision":8155} {"level":"info","ts":"2026-06-05T06:30:49.825902Z","caller":"traceutil/trace.go:171","msg":"trace[2003108205] linearizableReadLoop","detail":"{readStateIndex:14246; appliedIndex:14245; }","duration":"107.118813ms","start":"2026-06-05T06:30:49.718755Z","end":"2026-06-05T06:30:49.825874Z","steps":["trace[2003108205] 'read index received' (duration: 107.02098ms)","trace[2003108205] 'applied index is now lower than readState.Index' (duration: 96.653µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:30:49.826067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.303447ms","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-06-05T06:30:49.826105Z","caller":"traceutil/trace.go:171","msg":"trace[1238322052] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:13778; }","duration":"107.420611ms","start":"2026-06-05T06:30:49.718671Z","end":"2026-06-05T06:30:49.826092Z","steps":["trace[1238322052] 'agreement among raft nodes before linearized reading' (duration: 107.334698ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:30:49.826263Z","caller":"traceutil/trace.go:171","msg":"trace[1074498651] transaction","detail":"{read_only:false; response_revision:13778; number_of_response:1; }","duration":"109.511051ms","start":"2026-06-05T06:30:49.716686Z","end":"2026-06-05T06:30:49.826197Z","steps":["trace[1074498651] 'process raft request' (duration: 109.039067ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:30:49.826736Z","caller":"traceutil/trace.go:171","msg":"trace[2043192615] transaction","detail":"{read_only:false; response_revision:13779; number_of_response:1; }","duration":"105.035563ms","start":"2026-06-05T06:30:49.721666Z","end":"2026-06-05T06:30:49.826701Z","steps":["trace[2043192615] 'process raft request' (duration: 104.819957ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:30:49.826947Z","caller":"traceutil/trace.go:171","msg":"trace[2128718172] transaction","detail":"{read_only:false; response_revision:13780; number_of_response:1; }","duration":"102.626594ms","start":"2026-06-05T06:30:49.724226Z","end":"2026-06-05T06:30:49.826853Z","steps":["trace[2128718172] 'process raft request' (duration: 102.430039ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:30:53.956727Z","caller":"traceutil/trace.go:171","msg":"trace[1847694421] transaction","detail":"{read_only:false; response_revision:13799; number_of_response:1; }","duration":"118.348695ms","start":"2026-06-05T06:30:53.838352Z","end":"2026-06-05T06:30:53.956701Z","steps":["trace[1847694421] 'process raft request' (duration: 118.18121ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:30:53.957758Z","caller":"traceutil/trace.go:171","msg":"trace[1670513623] transaction","detail":"{read_only:false; response_revision:13801; number_of_response:1; }","duration":"117.107719ms","start":"2026-06-05T06:30:53.840632Z","end":"2026-06-05T06:30:53.957739Z","steps":["trace[1670513623] 'process raft request' (duration: 117.046337ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:30:53.958071Z","caller":"traceutil/trace.go:171","msg":"trace[1490054556] transaction","detail":"{read_only:false; response_revision:13800; number_of_response:1; }","duration":"118.962812ms","start":"2026-06-05T06:30:53.839097Z","end":"2026-06-05T06:30:53.95806Z","steps":["trace[1490054556] 'process raft request' (duration: 118.491049ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:31:29.583846Z","caller":"traceutil/trace.go:171","msg":"trace[1736570815] transaction","detail":"{read_only:false; response_revision:14003; number_of_response:1; }","duration":"120.734943ms","start":"2026-06-05T06:31:29.463091Z","end":"2026-06-05T06:31:29.583826Z","steps":["trace[1736570815] 'process raft request' (duration: 120.330161ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:33:18.71855Z","caller":"traceutil/trace.go:171","msg":"trace[72005751] transaction","detail":"{read_only:false; response_revision:15257; number_of_response:1; }","duration":"253.12246ms","start":"2026-06-05T06:33:18.465403Z","end":"2026-06-05T06:33:18.718526Z","steps":["trace[72005751] 'process raft request' (duration: 253.017447ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:33:18.718561Z","caller":"traceutil/trace.go:171","msg":"trace[521721433] transaction","detail":"{read_only:false; response_revision:15256; number_of_response:1; }","duration":"254.480139ms","start":"2026-06-05T06:33:18.464002Z","end":"2026-06-05T06:33:18.718482Z","steps":["trace[521721433] 'process raft request' (duration: 254.262613ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:33:18.718905Z","caller":"traceutil/trace.go:171","msg":"trace[1611434595] transaction","detail":"{read_only:false; response_revision:15258; number_of_response:1; }","duration":"252.986326ms","start":"2026-06-05T06:33:18.465805Z","end":"2026-06-05T06:33:18.718791Z","steps":["trace[1611434595] 'process raft request' (duration: 252.657937ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:33:18.72538Z","caller":"traceutil/trace.go:171","msg":"trace[1135349246] transaction","detail":"{read_only:false; response_revision:15259; number_of_response:1; }","duration":"195.92896ms","start":"2026-06-05T06:33:18.529424Z","end":"2026-06-05T06:33:18.725353Z","steps":["trace[1135349246] 'process raft request' (duration: 195.698273ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:33:18.725374Z","caller":"traceutil/trace.go:171","msg":"trace[1798805983] linearizableReadLoop","detail":"{readStateIndex:15775; appliedIndex:15772; }","duration":"121.070552ms","start":"2026-06-05T06:33:18.604259Z","end":"2026-06-05T06:33:18.725329Z","steps":["trace[1798805983] 'read index received' (duration: 114.191775ms)","trace[1798805983] 'applied index is now lower than readState.Index' (duration: 6.876887ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:33:18.725911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.308879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T06:33:18.72602Z","caller":"traceutil/trace.go:171","msg":"trace[2066588336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15259; }","duration":"121.782854ms","start":"2026-06-05T06:33:18.604209Z","end":"2026-06-05T06:33:18.725992Z","steps":["trace[2066588336] 'agreement among raft nodes before linearized reading' (duration: 121.236567ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:33:45.299576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12858} {"level":"info","ts":"2026-06-05T06:33:45.374116Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12858,"took":"71.861322ms","hash":1283520778,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":31006720,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-05T06:33:45.374274Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1283520778,"revision":12858,"compact-revision":10755} {"level":"info","ts":"2026-06-05T06:38:45.307996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15393} {"level":"info","ts":"2026-06-05T06:38:45.408335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15393,"took":"95.704284ms","hash":2221197933,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":32423936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-05T06:38:45.408428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2221197933,"revision":15393,"compact-revision":12858} {"level":"info","ts":"2026-06-05T06:39:02.517631Z","caller":"traceutil/trace.go:171","msg":"trace[362551645] linearizableReadLoop","detail":"{readStateIndex:18395; appliedIndex:18394; }","duration":"186.723718ms","start":"2026-06-05T06:39:02.330882Z","end":"2026-06-05T06:39:02.517606Z","steps":["trace[362551645] 'read index received' (duration: 186.560334ms)","trace[362551645] 'applied index is now lower than readState.Index' (duration: 162.324µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T06:39:02.517849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.946454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-06-05T06:39:02.517986Z","caller":"traceutil/trace.go:171","msg":"trace[943573867] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:17794; }","duration":"187.129009ms","start":"2026-06-05T06:39:02.330839Z","end":"2026-06-05T06:39:02.517968Z","steps":["trace[943573867] 'agreement among raft nodes before linearized reading' (duration: 186.872482ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:39:02.518436Z","caller":"traceutil/trace.go:171","msg":"trace[1218647958] transaction","detail":"{read_only:false; response_revision:17794; number_of_response:1; }","duration":"278.930991ms","start":"2026-06-05T06:39:02.239488Z","end":"2026-06-05T06:39:02.518419Z","steps":["trace[1218647958] 'process raft request' (duration: 277.988814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:39:02.839233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.757941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T06:39:02.839311Z","caller":"traceutil/trace.go:171","msg":"trace[453405548] linearizableReadLoop","detail":"{readStateIndex:18396; appliedIndex:18395; }","duration":"201.455411ms","start":"2026-06-05T06:39:02.637829Z","end":"2026-06-05T06:39:02.839284Z","steps":["trace[453405548] 'read index received' (duration: 198.197458ms)","trace[453405548] 'applied index is now lower than readState.Index' (duration: 3.256683ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:39:02.839364Z","caller":"traceutil/trace.go:171","msg":"trace[215451592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17794; }","duration":"237.918256ms","start":"2026-06-05T06:39:02.601425Z","end":"2026-06-05T06:39:02.839343Z","steps":["trace[215451592] 'range keys from in-memory index tree' (duration: 237.689109ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:39:02.839381Z","caller":"traceutil/trace.go:171","msg":"trace[1502067642] transaction","detail":"{read_only:false; response_revision:17795; number_of_response:1; }","duration":"213.500176ms","start":"2026-06-05T06:39:02.625847Z","end":"2026-06-05T06:39:02.839348Z","steps":["trace[1502067642] 'process raft request' (duration: 210.246613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:39:02.839532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.683507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-vb4xj\" ","response":"range_response_count:1 size:22090"} {"level":"warn","ts":"2026-06-05T06:39:02.839657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.617272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-06-05T06:39:02.839843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.923969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-fqhh8\" ","response":"range_response_count:1 size:20846"} {"level":"info","ts":"2026-06-05T06:39:02.839896Z","caller":"traceutil/trace.go:171","msg":"trace[895840325] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-fqhh8; range_end:; response_count:1; response_revision:17795; }","duration":"139.000131ms","start":"2026-06-05T06:39:02.70088Z","end":"2026-06-05T06:39:02.83988Z","steps":["trace[895840325] 'agreement among raft nodes before linearized reading' (duration: 138.879247ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:39:02.839899Z","caller":"traceutil/trace.go:171","msg":"trace[1097071677] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17795; }","duration":"174.90993ms","start":"2026-06-05T06:39:02.664968Z","end":"2026-06-05T06:39:02.839877Z","steps":["trace[1097071677] 'agreement among raft nodes before linearized reading' (duration: 174.518119ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:39:02.840104Z","caller":"traceutil/trace.go:171","msg":"trace[186429896] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-vb4xj; range_end:; response_count:1; response_revision:17795; }","duration":"202.295254ms","start":"2026-06-05T06:39:02.637799Z","end":"2026-06-05T06:39:02.840095Z","steps":["trace[186429896] 'agreement among raft nodes before linearized reading' (duration: 201.614315ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:39:03.088463Z","caller":"traceutil/trace.go:171","msg":"trace[758383210] linearizableReadLoop","detail":"{readStateIndex:18397; appliedIndex:18396; }","duration":"125.842735ms","start":"2026-06-05T06:39:02.962591Z","end":"2026-06-05T06:39:03.088434Z","steps":["trace[758383210] 'read index received' (duration: 125.561417ms)","trace[758383210] 'applied index is now lower than readState.Index' (duration: 277.338µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T06:39:03.08854Z","caller":"traceutil/trace.go:171","msg":"trace[1991609878] transaction","detail":"{read_only:false; response_revision:17796; number_of_response:1; }","duration":"233.684034ms","start":"2026-06-05T06:39:02.85483Z","end":"2026-06-05T06:39:03.088514Z","steps":["trace[1991609878] 'process raft request' (duration: 233.415306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:39:03.088734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.135783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-05T06:39:03.088784Z","caller":"traceutil/trace.go:171","msg":"trace[128393776] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17796; }","duration":"126.235826ms","start":"2026-06-05T06:39:02.962537Z","end":"2026-06-05T06:39:03.088773Z","steps":["trace[128393776] 'agreement among raft nodes before linearized reading' (duration: 126.047741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T06:39:03.08901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.395066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-server-7b67667fcf-r2d2f.18b61c097e226824\" ","response":"range_response_count:1 size:805"} {"level":"info","ts":"2026-06-05T06:39:03.089139Z","caller":"traceutil/trace.go:171","msg":"trace[1431146852] range","detail":"{range_begin:/registry/events/openstack/neutron-server-7b67667fcf-r2d2f.18b61c097e226824; range_end:; response_count:1; response_revision:17796; }","duration":"107.577731ms","start":"2026-06-05T06:39:02.981525Z","end":"2026-06-05T06:39:03.089103Z","steps":["trace[1431146852] 'agreement among raft nodes before linearized reading' (duration: 107.18423ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:39:18.676445Z","caller":"traceutil/trace.go:171","msg":"trace[1109441210] transaction","detail":"{read_only:false; response_revision:17956; number_of_response:1; }","duration":"102.693072ms","start":"2026-06-05T06:39:18.573718Z","end":"2026-06-05T06:39:18.676411Z","steps":["trace[1109441210] 'process raft request' (duration: 102.137156ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T06:43:40.967804Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ba52c55cf4193752","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-05T06:43:40.972319Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-05T06:43:40.972438Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-05T06:43:45.313752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17674} {"level":"info","ts":"2026-06-05T06:43:45.410803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17674,"took":"93.869959ms","hash":2149719670,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":27688960,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-05T06:43:45.410934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2149719670,"revision":17674,"compact-revision":15393}