{"level":"warn","ts":"2026-10-02T18:52:03.527273Z","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-02T18:52:03.527357Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.213: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.213:2380","--initial-cluster=instance=https://162.253.55.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.213: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-02T18:52:03.527425Z","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-02T18:52:03.527437Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-10-02T18:52:03.527461Z","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-02T18:52:03.5282Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"]} {"level":"info","ts":"2026-10-02T18:52:03.528291Z","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.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.213: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-02T18:52:03.531323Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.768743ms"} {"level":"info","ts":"2026-10-02T18:52:03.537744Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"43ce62d0a10d51da","cluster-id":"25e0c2268a6b42c6"} {"level":"info","ts":"2026-10-02T18:52:03.537819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T18:52:03.537839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 0"} {"level":"info","ts":"2026-10-02T18:52:03.537857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 43ce62d0a10d51da [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T18:52:03.53787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 1"} {"level":"info","ts":"2026-10-02T18:52:03.537917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"warn","ts":"2026-10-02T18:52:03.550385Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T18:52:03.552211Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T18:52:03.553671Z","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-02T18:52:03.556674Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"43ce62d0a10d51da","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T18:52:03.556871Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"43ce62d0a10d51da","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T18:52:03.556983Z","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-02T18:52:03.557098Z","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-02T18:52:03.557118Z","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-02T18:52:03.557961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"info","ts":"2026-10-02T18:52:03.567109Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","added-peer-id":"43ce62d0a10d51da","added-peer-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-10-02T18:52:03.570738Z","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-02T18:52:03.570908Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-10-02T18:52:03.570952Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-10-02T18:52:03.571098Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"43ce62d0a10d51da","initial-advertise-peer-urls":["https://162.253.55.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T18:52:03.571143Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T18:52:04.13871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T18:52:04.138799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T18:52:04.138833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgPreVoteResp from 43ce62d0a10d51da at term 1"} {"level":"info","ts":"2026-10-02T18:52:04.13885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became candidate at term 2"} {"level":"info","ts":"2026-10-02T18:52:04.138855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgVoteResp from 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-10-02T18:52:04.138865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became leader at term 2"} {"level":"info","ts":"2026-10-02T18:52:04.138873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 43ce62d0a10d51da elected leader 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-10-02T18:52:04.139972Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T18:52:04.141244Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T18:52:04.141264Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T18:52:04.141257Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"43ce62d0a10d51da","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.213:2379]}","request-path":"/0/members/43ce62d0a10d51da/attributes","cluster-id":"25e0c2268a6b42c6","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T18:52:04.141727Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T18:52:04.141813Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T18:52:04.143487Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.213:2379"} {"level":"info","ts":"2026-10-02T18:52:04.144741Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T18:52:04.145329Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T18:52:04.145445Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T18:52:04.145487Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} 2026/10/02 18:52:09 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-10-02T18:52:23.240011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.924898ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-02T18:52:23.346835Z","caller":"traceutil/trace.go:171","msg":"trace[618653388] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"101.710464ms","start":"2026-10-02T18:52:23.245082Z","end":"2026-10-02T18:52:23.346792Z","steps":["trace[618653388] 'process raft request' (duration: 96.18611ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:47.055834Z","caller":"traceutil/trace.go:171","msg":"trace[854350709] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"104.872684ms","start":"2026-10-02T18:52:46.950927Z","end":"2026-10-02T18:52:47.0558Z","steps":["trace[854350709] 'process raft request' (duration: 103.181932ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:47.232239Z","caller":"traceutil/trace.go:171","msg":"trace[715328079] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"105.348783ms","start":"2026-10-02T18:52:47.126868Z","end":"2026-10-02T18:52:47.232217Z","steps":["trace[715328079] 'process raft request' (duration: 105.18508ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:47.735889Z","caller":"traceutil/trace.go:171","msg":"trace[145658247] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"209.34073ms","start":"2026-10-02T18:52:47.526522Z","end":"2026-10-02T18:52:47.735863Z","steps":["trace[145658247] 'read index received' (duration: 209.286509ms)","trace[145658247] 'applied index is now lower than readState.Index' (duration: 52.701µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:52:47.736013Z","caller":"traceutil/trace.go:171","msg":"trace[1983682033] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"233.268372ms","start":"2026-10-02T18:52:47.502694Z","end":"2026-10-02T18:52:47.735962Z","steps":["trace[1983682033] 'process raft request' (duration: 232.998067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:52:47.736224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.627115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b4rtc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-02T18:52:47.736293Z","caller":"traceutil/trace.go:171","msg":"trace[730949866] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b4rtc; range_end:; response_count:1; response_revision:598; }","duration":"209.801079ms","start":"2026-10-02T18:52:47.526472Z","end":"2026-10-02T18:52:47.736273Z","steps":["trace[730949866] 'agreement among raft nodes before linearized reading' (duration: 209.535173ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:47.890073Z","caller":"traceutil/trace.go:171","msg":"trace[1207105998] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"154.077434ms","start":"2026-10-02T18:52:47.735968Z","end":"2026-10-02T18:52:47.890045Z","steps":["trace[1207105998] 'read index received' (duration: 153.167196ms)","trace[1207105998] 'applied index is now lower than readState.Index' (duration: 908.738µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T18:52:47.890249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.070434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.213\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-02T18:52:47.890318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.109127ms","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-10-02T18:52:47.890267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.881599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T18:52:47.890356Z","caller":"traceutil/trace.go:171","msg":"trace[514600829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"355.21963ms","start":"2026-10-02T18:52:47.535124Z","end":"2026-10-02T18:52:47.890343Z","steps":["trace[514600829] 'agreement among raft nodes before linearized reading' (duration: 355.025816ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:47.890375Z","caller":"traceutil/trace.go:171","msg":"trace[341954480] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"228.457232ms","start":"2026-10-02T18:52:47.661853Z","end":"2026-10-02T18:52:47.89031Z","steps":["trace[341954480] 'process raft request' (duration: 227.525824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:52:47.890442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T18:52:47.535112Z","time spent":"355.321081ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-02T18:52:47.890452Z","caller":"traceutil/trace.go:171","msg":"trace[1014618351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"312.169785ms","start":"2026-10-02T18:52:47.578256Z","end":"2026-10-02T18:52:47.890425Z","steps":["trace[1014618351] 'agreement among raft nodes before linearized reading' (duration: 311.807688ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:52:47.890338Z","caller":"traceutil/trace.go:171","msg":"trace[1347452955] range","detail":"{range_begin:/registry/masterleases/162.253.55.213; range_end:; response_count:1; response_revision:599; }","duration":"321.214126ms","start":"2026-10-02T18:52:47.569098Z","end":"2026-10-02T18:52:47.890312Z","steps":["trace[1347452955] 'agreement among raft nodes before linearized reading' (duration: 321.009372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:52:47.89059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T18:52:47.578245Z","time spent":"312.326827ms","remote":"127.0.0.1:47624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-02T18:52:47.890629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T18:52:47.569075Z","time spent":"321.536552ms","remote":"127.0.0.1:47662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.213\" "} {"level":"warn","ts":"2026-10-02T18:52:47.891058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.328171ms","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-02T18:52:47.891234Z","caller":"traceutil/trace.go:171","msg":"trace[330771627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"336.538035ms","start":"2026-10-02T18:52:47.554665Z","end":"2026-10-02T18:52:47.891203Z","steps":["trace[330771627] 'agreement among raft nodes before linearized reading' (duration: 335.591408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:52:47.891519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T18:52:47.554652Z","time spent":"336.806881ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-02T18:52:47.897538Z","caller":"traceutil/trace.go:171","msg":"trace[189123788] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"141.981695ms","start":"2026-10-02T18:52:47.755493Z","end":"2026-10-02T18:52:47.897475Z","steps":["trace[189123788] 'process raft request' (duration: 141.799991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:53:03.767696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.67272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T18:53:03.767872Z","caller":"traceutil/trace.go:171","msg":"trace[367982061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"189.909434ms","start":"2026-10-02T18:53:03.577935Z","end":"2026-10-02T18:53:03.767844Z","steps":["trace[367982061] 'range keys from in-memory index tree' (duration: 189.581807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:53:03.767869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.921335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bxnwm\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-02T18:53:03.767959Z","caller":"traceutil/trace.go:171","msg":"trace[294748285] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bxnwm; range_end:; response_count:1; response_revision:768; }","duration":"150.069087ms","start":"2026-10-02T18:53:03.617868Z","end":"2026-10-02T18:53:03.767937Z","steps":["trace[294748285] 'range keys from in-memory index tree' (duration: 149.733641ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:53:55.038588Z","caller":"traceutil/trace.go:171","msg":"trace[1665855089] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"100.756714ms","start":"2026-10-02T18:53:54.937807Z","end":"2026-10-02T18:53:55.038564Z","steps":["trace[1665855089] 'process raft request' (duration: 100.587641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:54:22.805358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.683675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T18:54:22.805484Z","caller":"traceutil/trace.go:171","msg":"trace[2106727698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1856; }","duration":"226.89587ms","start":"2026-10-02T18:54:22.578562Z","end":"2026-10-02T18:54:22.805458Z","steps":["trace[2106727698] 'range keys from in-memory index tree' (duration: 226.563543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:54:22.805672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.345117ms","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-02T18:54:22.805765Z","caller":"traceutil/trace.go:171","msg":"trace[860045033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1856; }","duration":"248.47683ms","start":"2026-10-02T18:54:22.557267Z","end":"2026-10-02T18:54:22.805744Z","steps":["trace[860045033] 'range keys from in-memory index tree' (duration: 248.105903ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:54:22.805839Z","caller":"traceutil/trace.go:171","msg":"trace[1427363866] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"111.808554ms","start":"2026-10-02T18:54:22.693989Z","end":"2026-10-02T18:54:22.805798Z","steps":["trace[1427363866] 'process raft request' (duration: 97.899679ms)","trace[1427363866] 'compare' (duration: 13.359964ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:54:23.265298Z","caller":"traceutil/trace.go:171","msg":"trace[1154588866] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"241.837762ms","start":"2026-10-02T18:54:23.023429Z","end":"2026-10-02T18:54:23.265266Z","steps":["trace[1154588866] 'process raft request' (duration: 241.618359ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:54:23.270867Z","caller":"traceutil/trace.go:171","msg":"trace[1572562527] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"114.977584ms","start":"2026-10-02T18:54:23.155753Z","end":"2026-10-02T18:54:23.270731Z","steps":["trace[1572562527] 'process raft request' (duration: 114.230879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:55:12.990559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.565152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T18:55:12.990781Z","caller":"traceutil/trace.go:171","msg":"trace[650850504] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"221.056901ms","start":"2026-10-02T18:55:12.769694Z","end":"2026-10-02T18:55:12.990751Z","steps":["trace[650850504] 'process raft request' (duration: 89.817887ms)","trace[650850504] 'compare' (duration: 130.421579ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:55:13.374614Z","caller":"traceutil/trace.go:171","msg":"trace[46022970] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"147.947672ms","start":"2026-10-02T18:55:13.226639Z","end":"2026-10-02T18:55:13.374587Z","steps":["trace[46022970] 'process raft request' (duration: 147.799059ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:55:13.381707Z","caller":"traceutil/trace.go:171","msg":"trace[835698314] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"112.912366ms","start":"2026-10-02T18:55:13.268777Z","end":"2026-10-02T18:55:13.381689Z","steps":["trace[835698314] 'process raft request' (duration: 112.822044ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:55:18.818495Z","caller":"traceutil/trace.go:171","msg":"trace[752912017] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"174.258342ms","start":"2026-10-02T18:55:18.644208Z","end":"2026-10-02T18:55:18.818467Z","steps":["trace[752912017] 'process raft request' (duration: 165.364683ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:56:05.414068Z","caller":"traceutil/trace.go:171","msg":"trace[185183359] transaction","detail":"{read_only:false; response_revision:2634; number_of_response:1; }","duration":"194.220174ms","start":"2026-10-02T18:56:05.219826Z","end":"2026-10-02T18:56:05.414046Z","steps":["trace[185183359] 'process raft request' (duration: 194.063141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:56:39.339353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.074125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T18:56:39.339467Z","caller":"traceutil/trace.go:171","msg":"trace[2143270827] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"246.862344ms","start":"2026-10-02T18:56:39.092583Z","end":"2026-10-02T18:56:39.339446Z","steps":["trace[2143270827] 'process raft request' (duration: 117.576706ms)","trace[2143270827] 'compare' (duration: 128.711908ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:56:39.348208Z","caller":"traceutil/trace.go:171","msg":"trace[1069978104] linearizableReadLoop","detail":"{readStateIndex:2949; appliedIndex:2947; }","duration":"200.34743ms","start":"2026-10-02T18:56:39.147842Z","end":"2026-10-02T18:56:39.34819Z","steps":["trace[1069978104] 'read index received' (duration: 62.332846ms)","trace[1069978104] 'applied index is now lower than readState.Index' (duration: 138.013904ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T18:56:39.348501Z","caller":"traceutil/trace.go:171","msg":"trace[908288455] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"252.818637ms","start":"2026-10-02T18:56:39.095671Z","end":"2026-10-02T18:56:39.348489Z","steps":["trace[908288455] 'process raft request' (duration: 252.422679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:56:39.348794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.828169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T18:56:39.34883Z","caller":"traceutil/trace.go:171","msg":"trace[1123510303] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2838; }","duration":"201.021492ms","start":"2026-10-02T18:56:39.147797Z","end":"2026-10-02T18:56:39.348818Z","steps":["trace[1123510303] 'agreement among raft nodes before linearized reading' (duration: 200.851209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T18:56:39.349042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.097629ms","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-02T18:56:39.349186Z","caller":"traceutil/trace.go:171","msg":"trace[1641585974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2838; }","duration":"166.314703ms","start":"2026-10-02T18:56:39.182856Z","end":"2026-10-02T18:56:39.349171Z","steps":["trace[1641585974] 'agreement among raft nodes before linearized reading' (duration: 165.978187ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T18:59:46.081671Z","caller":"traceutil/trace.go:171","msg":"trace[635599721] transaction","detail":"{read_only:false; response_revision:4026; number_of_response:1; }","duration":"107.306938ms","start":"2026-10-02T18:59:45.974337Z","end":"2026-10-02T18:59:46.081644Z","steps":["trace[635599721] 'process raft request' (duration: 107.130165ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:01:10.357877Z","caller":"traceutil/trace.go:171","msg":"trace[242495576] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"153.521225ms","start":"2026-10-02T19:01:10.20431Z","end":"2026-10-02T19:01:10.357831Z","steps":["trace[242495576] 'process raft request' (duration: 146.731557ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:01:10.35788Z","caller":"traceutil/trace.go:171","msg":"trace[1323305663] linearizableReadLoop","detail":"{readStateIndex:5259; appliedIndex:5257; }","duration":"138.264645ms","start":"2026-10-02T19:01:10.219591Z","end":"2026-10-02T19:01:10.357855Z","steps":["trace[1323305663] 'read index received' (duration: 131.443126ms)","trace[1323305663] 'applied index is now lower than readState.Index' (duration: 6.820279ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:01:10.35837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.477716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:529477"} {"level":"info","ts":"2026-10-02T19:01:10.358441Z","caller":"traceutil/trace.go:171","msg":"trace[6296483] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5065; }","duration":"142.67562ms","start":"2026-10-02T19:01:10.215754Z","end":"2026-10-02T19:01:10.358429Z","steps":["trace[6296483] 'agreement among raft nodes before linearized reading' (duration: 142.15114ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:01:10.358642Z","caller":"traceutil/trace.go:171","msg":"trace[1860660174] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"147.772996ms","start":"2026-10-02T19:01:10.210858Z","end":"2026-10-02T19:01:10.358631Z","steps":["trace[1860660174] 'process raft request' (duration: 146.888849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:01:10.358404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.28516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-02T19:01:10.358757Z","caller":"traceutil/trace.go:171","msg":"trace[670437596] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5065; }","duration":"135.630106ms","start":"2026-10-02T19:01:10.223083Z","end":"2026-10-02T19:01:10.358713Z","steps":["trace[670437596] 'agreement among raft nodes before linearized reading' (duration: 135.211078ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:01:14.240962Z","caller":"traceutil/trace.go:171","msg":"trace[1792213734] linearizableReadLoop","detail":"{readStateIndex:5404; appliedIndex:5403; }","duration":"158.616921ms","start":"2026-10-02T19:01:14.082324Z","end":"2026-10-02T19:01:14.240941Z","steps":["trace[1792213734] 'read index received' (duration: 158.395637ms)","trace[1792213734] 'applied index is now lower than readState.Index' (duration: 220.184µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:01:14.241038Z","caller":"traceutil/trace.go:171","msg":"trace[1744311430] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"160.994257ms","start":"2026-10-02T19:01:14.080003Z","end":"2026-10-02T19:01:14.240997Z","steps":["trace[1744311430] 'process raft request' (duration: 160.795293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:01:14.241131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.792936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:01:14.241172Z","caller":"traceutil/trace.go:171","msg":"trace[76060880] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5206; }","duration":"158.890677ms","start":"2026-10-02T19:01:14.082268Z","end":"2026-10-02T19:01:14.241159Z","steps":["trace[76060880] 'agreement among raft nodes before linearized reading' (duration: 158.811055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:01:29.707973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.688542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T19:01:29.708088Z","caller":"traceutil/trace.go:171","msg":"trace[1940679125] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"129.781844ms","start":"2026-10-02T19:01:29.57829Z","end":"2026-10-02T19:01:29.708072Z","steps":["trace[1940679125] 'process raft request' (duration: 28.905778ms)","trace[1940679125] 'compare' (duration: 100.514559ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:01:37.891716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.220618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T19:01:37.891866Z","caller":"traceutil/trace.go:171","msg":"trace[353509916] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5672; }","duration":"105.435402ms","start":"2026-10-02T19:01:37.786411Z","end":"2026-10-02T19:01:37.891847Z","steps":["trace[353509916] 'count revisions from in-memory index tree' (duration: 105.114276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:01:37.891899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.488029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-02T19:01:37.891952Z","caller":"traceutil/trace.go:171","msg":"trace[1374533602] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5672; }","duration":"119.56052ms","start":"2026-10-02T19:01:37.772378Z","end":"2026-10-02T19:01:37.891939Z","steps":["trace[1374533602] 'range keys from in-memory index tree' (duration: 119.303835ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:02:04.166089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-10-02T19:02:04.246645Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"79.123613ms","hash":4084199781,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":32739328,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-02T19:02:04.246706Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4084199781,"revision":2977,"compact-revision":-1} {"level":"info","ts":"2026-10-02T19:07:04.181861Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5912} {"level":"info","ts":"2026-10-02T19:07:04.270731Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5912,"took":"86.844978ms","hash":2506716494,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":26120192,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-02T19:07:04.270801Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2506716494,"revision":5912,"compact-revision":2977} {"level":"info","ts":"2026-10-02T19:08:11.350975Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022e7.wal"} {"level":"info","ts":"2026-10-02T19:08:15.549415Z","caller":"traceutil/trace.go:171","msg":"trace[294157543] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"175.55267ms","start":"2026-10-02T19:08:15.373826Z","end":"2026-10-02T19:08:15.549379Z","steps":["trace[294157543] 'process raft request' (duration: 108.522218ms)","trace[294157543] 'compare' (duration: 66.881299ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:08:16.309119Z","caller":"traceutil/trace.go:171","msg":"trace[415742720] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"139.995666ms","start":"2026-10-02T19:08:16.169104Z","end":"2026-10-02T19:08:16.3091Z","steps":["trace[415742720] 'process raft request' (duration: 139.866063ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:16.331571Z","caller":"traceutil/trace.go:171","msg":"trace[1438085007] linearizableReadLoop","detail":"{readStateIndex:8958; appliedIndex:8956; }","duration":"157.031399ms","start":"2026-10-02T19:08:16.174524Z","end":"2026-10-02T19:08:16.331555Z","steps":["trace[1438085007] 'read index received' (duration: 134.498411ms)","trace[1438085007] 'applied index is now lower than readState.Index' (duration: 22.532188ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:08:16.331651Z","caller":"traceutil/trace.go:171","msg":"trace[1971701786] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"162.34515ms","start":"2026-10-02T19:08:16.169287Z","end":"2026-10-02T19:08:16.331633Z","steps":["trace[1971701786] 'process raft request' (duration: 162.189027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:16.331728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.196301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-02T19:08:16.331757Z","caller":"traceutil/trace.go:171","msg":"trace[1529297143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8648; }","duration":"157.300844ms","start":"2026-10-02T19:08:16.174448Z","end":"2026-10-02T19:08:16.331749Z","steps":["trace[1529297143] 'agreement among raft nodes before linearized reading' (duration: 157.179762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:16.34589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.635517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:08:16.345971Z","caller":"traceutil/trace.go:171","msg":"trace[1236422760] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8649; }","duration":"170.753089ms","start":"2026-10-02T19:08:16.175198Z","end":"2026-10-02T19:08:16.345951Z","steps":["trace[1236422760] 'agreement among raft nodes before linearized reading' (duration: 170.593546ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:16.346233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.518324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:08:16.346273Z","caller":"traceutil/trace.go:171","msg":"trace[357821658] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8649; }","duration":"141.595796ms","start":"2026-10-02T19:08:16.204668Z","end":"2026-10-02T19:08:16.346264Z","steps":["trace[357821658] 'agreement among raft nodes before linearized reading' (duration: 141.533815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:16.346312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.138495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:08:16.346356Z","caller":"traceutil/trace.go:171","msg":"trace[157189739] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8649; }","duration":"142.223637ms","start":"2026-10-02T19:08:16.204119Z","end":"2026-10-02T19:08:16.346343Z","steps":["trace[157189739] 'agreement among raft nodes before linearized reading' (duration: 142.159796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:16.346319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.015204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T19:08:16.346432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.126612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:08:16.346419Z","caller":"traceutil/trace.go:171","msg":"trace[2010674882] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8649; }","duration":"170.119246ms","start":"2026-10-02T19:08:16.176273Z","end":"2026-10-02T19:08:16.346393Z","steps":["trace[2010674882] 'agreement among raft nodes before linearized reading' (duration: 169.994554ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:16.34645Z","caller":"traceutil/trace.go:171","msg":"trace[448735519] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8649; }","duration":"116.165762ms","start":"2026-10-02T19:08:16.230279Z","end":"2026-10-02T19:08:16.346444Z","steps":["trace[448735519] 'agreement among raft nodes before linearized reading' (duration: 116.136032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:16.346381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.084836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T19:08:16.346516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.431501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T19:08:16.346562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.257304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:08:16.346559Z","caller":"traceutil/trace.go:171","msg":"trace[381508646] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8649; }","duration":"171.273859ms","start":"2026-10-02T19:08:16.175267Z","end":"2026-10-02T19:08:16.346541Z","steps":["trace[381508646] 'agreement among raft nodes before linearized reading' (duration: 171.075175ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:16.346564Z","caller":"traceutil/trace.go:171","msg":"trace[2054968348] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8649; }","duration":"142.526813ms","start":"2026-10-02T19:08:16.204023Z","end":"2026-10-02T19:08:16.346549Z","steps":["trace[2054968348] 'agreement among raft nodes before linearized reading' (duration: 142.422271ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:16.346583Z","caller":"traceutil/trace.go:171","msg":"trace[1685892921] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8649; }","duration":"116.297605ms","start":"2026-10-02T19:08:16.23028Z","end":"2026-10-02T19:08:16.346577Z","steps":["trace[1685892921] 'agreement among raft nodes before linearized reading' (duration: 116.268584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:16.346662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.415127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:08:16.346691Z","caller":"traceutil/trace.go:171","msg":"trace[1443578383] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8649; }","duration":"116.467169ms","start":"2026-10-02T19:08:16.230214Z","end":"2026-10-02T19:08:16.346681Z","steps":["trace[1443578383] 'agreement among raft nodes before linearized reading' (duration: 116.427928ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:17.027306Z","caller":"traceutil/trace.go:171","msg":"trace[1516722405] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"271.112003ms","start":"2026-10-02T19:08:16.756165Z","end":"2026-10-02T19:08:17.027277Z","steps":["trace[1516722405] 'process raft request' (duration: 270.913559ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:17.080513Z","caller":"traceutil/trace.go:171","msg":"trace[174288439] linearizableReadLoop","detail":"{readStateIndex:8962; appliedIndex:8960; }","duration":"226.481866ms","start":"2026-10-02T19:08:16.853964Z","end":"2026-10-02T19:08:17.080446Z","steps":["trace[174288439] 'read index received' (duration: 173.183665ms)","trace[174288439] 'applied index is now lower than readState.Index' (duration: 53.297181ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:08:17.080543Z","caller":"traceutil/trace.go:171","msg":"trace[626478175] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"226.858573ms","start":"2026-10-02T19:08:16.853648Z","end":"2026-10-02T19:08:17.080506Z","steps":["trace[626478175] 'process raft request' (duration: 187.536897ms)","trace[626478175] 'compare' (duration: 39.148433ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:08:17.080655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.66857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:08:17.080709Z","caller":"traceutil/trace.go:171","msg":"trace[1516525765] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8652; }","duration":"226.768342ms","start":"2026-10-02T19:08:16.853927Z","end":"2026-10-02T19:08:17.080696Z","steps":["trace[1516525765] 'agreement among raft nodes before linearized reading' (duration: 226.66759ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:46.614578Z","caller":"traceutil/trace.go:171","msg":"trace[1419828070] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"185.0339ms","start":"2026-10-02T19:08:46.429501Z","end":"2026-10-02T19:08:46.614555Z","steps":["trace[1419828070] 'process raft request' (duration: 184.869387ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:46.780022Z","caller":"traceutil/trace.go:171","msg":"trace[1869557023] transaction","detail":"{read_only:false; response_revision:8861; number_of_response:1; }","duration":"344.154008ms","start":"2026-10-02T19:08:46.43583Z","end":"2026-10-02T19:08:46.779984Z","steps":["trace[1869557023] 'process raft request' (duration: 343.519437ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:08:46.780108Z","caller":"traceutil/trace.go:171","msg":"trace[204800955] linearizableReadLoop","detail":"{readStateIndex:9181; appliedIndex:9178; }","duration":"265.999896ms","start":"2026-10-02T19:08:46.514077Z","end":"2026-10-02T19:08:46.780077Z","steps":["trace[204800955] 'read index received' (duration: 100.310343ms)","trace[204800955] 'applied index is now lower than readState.Index' (duration: 165.688233ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:08:46.780187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:08:46.435802Z","time spent":"344.289111ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:08:46.780389Z","caller":"traceutil/trace.go:171","msg":"trace[1419120420] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"324.953355ms","start":"2026-10-02T19:08:46.455416Z","end":"2026-10-02T19:08:46.780369Z","steps":["trace[1419120420] 'process raft request' (duration: 324.536276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:46.780578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:08:46.455402Z","time spent":"325.068987ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:08:46.780616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.008892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T19:08:46.780663Z","caller":"traceutil/trace.go:171","msg":"trace[764128727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8862; }","duration":"202.076633ms","start":"2026-10-02T19:08:46.578575Z","end":"2026-10-02T19:08:46.780651Z","steps":["trace[764128727] 'agreement among raft nodes before linearized reading' (duration: 202.001642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:08:46.780751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.668408ms","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-02T19:08:46.780826Z","caller":"traceutil/trace.go:171","msg":"trace[2118907562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8862; }","duration":"266.796231ms","start":"2026-10-02T19:08:46.514013Z","end":"2026-10-02T19:08:46.78081Z","steps":["trace[2118907562] 'agreement among raft nodes before linearized reading' (duration: 266.576077ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:10:08.901664Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"43ce62d0a10d51da","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T19:10:08.909722Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-02T19:10:08.909823Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-02T19:10:15.260588Z","caller":"traceutil/trace.go:171","msg":"trace[162018468] transaction","detail":"{read_only:false; response_revision:9711; number_of_response:1; }","duration":"115.026211ms","start":"2026-10-02T19:10:15.145538Z","end":"2026-10-02T19:10:15.260565Z","steps":["trace[162018468] 'process raft request' (duration: 114.891559ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:10:28.417839Z","caller":"traceutil/trace.go:171","msg":"trace[1892238137] transaction","detail":"{read_only:false; response_revision:9788; number_of_response:1; }","duration":"107.212143ms","start":"2026-10-02T19:10:28.310591Z","end":"2026-10-02T19:10:28.417803Z","steps":["trace[1892238137] 'process raft request' (duration: 107.008559ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:12:04.191061Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8065} {"level":"info","ts":"2026-10-02T19:12:04.262777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8065,"took":"68.623629ms","hash":1475220459,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":28553216,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-02T19:12:04.262849Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1475220459,"revision":8065,"compact-revision":5912} {"level":"info","ts":"2026-10-02T19:16:09.092933Z","caller":"traceutil/trace.go:171","msg":"trace[73939398] transaction","detail":"{read_only:false; response_revision:12231; number_of_response:1; }","duration":"152.42078ms","start":"2026-10-02T19:16:08.940481Z","end":"2026-10-02T19:16:09.092901Z","steps":["trace[73939398] 'process raft request' (duration: 152.222936ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:17:04.197117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10447} {"level":"info","ts":"2026-10-02T19:17:04.278325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10447,"took":"79.035108ms","hash":3168400416,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":26357760,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-02T19:17:04.278392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3168400416,"revision":10447,"compact-revision":8065} {"level":"info","ts":"2026-10-02T19:22:04.234781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12603} {"level":"info","ts":"2026-10-02T19:22:04.437593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12603,"took":"199.292218ms","hash":363435038,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":27938816,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T19:22:04.437659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":363435038,"revision":12603,"compact-revision":10447} {"level":"info","ts":"2026-10-02T19:22:20.124673Z","caller":"traceutil/trace.go:171","msg":"trace[1016499060] transaction","detail":"{read_only:false; response_revision:14740; number_of_response:1; }","duration":"148.497675ms","start":"2026-10-02T19:22:19.976139Z","end":"2026-10-02T19:22:20.124636Z","steps":["trace[1016499060] 'process raft request' (duration: 148.120868ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:22:58.895692Z","caller":"traceutil/trace.go:171","msg":"trace[1848049801] transaction","detail":"{read_only:false; response_revision:14953; number_of_response:1; }","duration":"241.334096ms","start":"2026-10-02T19:22:58.654322Z","end":"2026-10-02T19:22:58.895656Z","steps":["trace[1848049801] 'process raft request' (duration: 241.132822ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:23:23.09127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.05079ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-02T19:23:23.091633Z","caller":"traceutil/trace.go:171","msg":"trace[2094717607] linearizableReadLoop","detail":"{readStateIndex:15813; appliedIndex:15812; }","duration":"213.13616ms","start":"2026-10-02T19:23:22.878465Z","end":"2026-10-02T19:23:23.091602Z","steps":["trace[2094717607] 'read index received' (duration: 77.750494ms)","trace[2094717607] 'applied index is now lower than readState.Index' (duration: 135.382436ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:23:23.091927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.455886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-zmtvw\" ","response":"range_response_count:1 size:20644"} {"level":"info","ts":"2026-10-02T19:23:23.091975Z","caller":"traceutil/trace.go:171","msg":"trace[838148740] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-zmtvw; range_end:; response_count:1; response_revision:15273; }","duration":"213.578829ms","start":"2026-10-02T19:23:22.878383Z","end":"2026-10-02T19:23:23.091962Z","steps":["trace[838148740] 'agreement among raft nodes before linearized reading' (duration: 213.367165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:23:23.097188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.996783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T19:23:23.09727Z","caller":"traceutil/trace.go:171","msg":"trace[1793549749] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:15274; }","duration":"141.131926ms","start":"2026-10-02T19:23:22.956119Z","end":"2026-10-02T19:23:23.097251Z","steps":["trace[1793549749] 'agreement among raft nodes before linearized reading' (duration: 140.975132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:24:50.527057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.587847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T19:24:50.527175Z","caller":"traceutil/trace.go:171","msg":"trace[360271069] transaction","detail":"{read_only:false; response_revision:16089; number_of_response:1; }","duration":"123.455491ms","start":"2026-10-02T19:24:50.4037Z","end":"2026-10-02T19:24:50.527156Z","steps":["trace[360271069] 'process raft request' (duration: 22.726861ms)","trace[360271069] 'compare' (duration: 100.422634ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:24:50.670777Z","caller":"traceutil/trace.go:171","msg":"trace[1041001069] linearizableReadLoop","detail":"{readStateIndex:16656; appliedIndex:16654; }","duration":"103.605864ms","start":"2026-10-02T19:24:50.567134Z","end":"2026-10-02T19:24:50.67074Z","steps":["trace[1041001069] 'read index received' (duration: 34.641516ms)","trace[1041001069] 'applied index is now lower than readState.Index' (duration: 68.962808ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:24:50.670838Z","caller":"traceutil/trace.go:171","msg":"trace[1036247244] transaction","detail":"{read_only:false; response_revision:16091; number_of_response:1; }","duration":"128.893424ms","start":"2026-10-02T19:24:50.541901Z","end":"2026-10-02T19:24:50.670794Z","steps":["trace[1036247244] 'process raft request' (duration: 120.355082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:24:50.67101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.550525ms","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-02T19:24:50.671744Z","caller":"traceutil/trace.go:171","msg":"trace[1803956625] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16091; }","duration":"104.337147ms","start":"2026-10-02T19:24:50.567391Z","end":"2026-10-02T19:24:50.671728Z","steps":["trace[1803956625] 'agreement among raft nodes before linearized reading' (duration: 103.541093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:24:50.671095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.934981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-02T19:24:50.671895Z","caller":"traceutil/trace.go:171","msg":"trace[1343089526] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16091; }","duration":"104.798876ms","start":"2026-10-02T19:24:50.567086Z","end":"2026-10-02T19:24:50.671885Z","steps":["trace[1343089526] 'agreement among raft nodes before linearized reading' (duration: 103.821049ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:24:50.896137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.544288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T19:24:50.896114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.060409ms","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-02T19:24:50.896211Z","caller":"traceutil/trace.go:171","msg":"trace[286520600] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16091; }","duration":"108.66762ms","start":"2026-10-02T19:24:50.787524Z","end":"2026-10-02T19:24:50.896192Z","steps":["trace[286520600] 'range keys from in-memory index tree' (duration: 108.445516ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:24:50.896234Z","caller":"traceutil/trace.go:171","msg":"trace[1631564535] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16091; }","duration":"108.231202ms","start":"2026-10-02T19:24:50.787977Z","end":"2026-10-02T19:24:50.896208Z","steps":["trace[1631564535] 'range keys from in-memory index tree' (duration: 107.991217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:24:50.896243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.13024ms","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-02T19:24:50.896332Z","caller":"traceutil/trace.go:171","msg":"trace[1273890413] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16091; }","duration":"108.274613ms","start":"2026-10-02T19:24:50.788033Z","end":"2026-10-02T19:24:50.896308Z","steps":["trace[1273890413] 'range keys from in-memory index tree' (duration: 107.979447ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:24:51.104456Z","caller":"traceutil/trace.go:171","msg":"trace[260874607] linearizableReadLoop","detail":"{readStateIndex:16658; appliedIndex:16657; }","duration":"129.625399ms","start":"2026-10-02T19:24:50.974793Z","end":"2026-10-02T19:24:51.104418Z","steps":["trace[260874607] 'read index received' (duration: 94.87925ms)","trace[260874607] 'applied index is now lower than readState.Index' (duration: 34.744689ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:24:51.104616Z","caller":"traceutil/trace.go:171","msg":"trace[2146056722] transaction","detail":"{read_only:false; response_revision:16093; number_of_response:1; }","duration":"145.145903ms","start":"2026-10-02T19:24:50.959452Z","end":"2026-10-02T19:24:51.104598Z","steps":["trace[2146056722] 'process raft request' (duration: 110.234701ms)","trace[2146056722] 'compare' (duration: 34.471434ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:24:51.105002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.341803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-02T19:24:51.105086Z","caller":"traceutil/trace.go:171","msg":"trace[1604292962] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16093; }","duration":"128.463456ms","start":"2026-10-02T19:24:50.976603Z","end":"2026-10-02T19:24:51.105066Z","steps":["trace[1604292962] 'agreement among raft nodes before linearized reading' (duration: 128.191892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:24:51.105172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.362972ms","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-02T19:24:51.105213Z","caller":"traceutil/trace.go:171","msg":"trace[320283359] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16093; }","duration":"130.448773ms","start":"2026-10-02T19:24:50.974751Z","end":"2026-10-02T19:24:51.1052Z","steps":["trace[320283359] 'agreement among raft nodes before linearized reading' (duration: 130.376251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:24:51.105446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.447736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-10-02T19:24:51.105593Z","caller":"traceutil/trace.go:171","msg":"trace[572342215] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16093; }","duration":"127.62194ms","start":"2026-10-02T19:24:50.977944Z","end":"2026-10-02T19:24:51.105566Z","steps":["trace[572342215] 'agreement among raft nodes before linearized reading' (duration: 127.292964ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:27:04.244066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14609} {"level":"info","ts":"2026-10-02T19:27:04.321859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14609,"took":"75.006922ms","hash":4221619432,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":31092736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T19:27:04.321936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4221619432,"revision":14609,"compact-revision":12603} {"level":"info","ts":"2026-10-02T19:31:06.06998Z","caller":"traceutil/trace.go:171","msg":"trace[1899280838] linearizableReadLoop","detail":"{readStateIndex:19469; appliedIndex:19468; }","duration":"269.378049ms","start":"2026-10-02T19:31:05.800562Z","end":"2026-10-02T19:31:06.06994Z","steps":["trace[1899280838] 'read index received' (duration: 269.174285ms)","trace[1899280838] 'applied index is now lower than readState.Index' (duration: 202.454µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:31:06.070037Z","caller":"traceutil/trace.go:171","msg":"trace[2034528483] transaction","detail":"{read_only:false; response_revision:18812; number_of_response:1; }","duration":"274.548236ms","start":"2026-10-02T19:31:05.795456Z","end":"2026-10-02T19:31:06.070005Z","steps":["trace[2034528483] 'process raft request' (duration: 274.304451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:31:06.070225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.643184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-10-02T19:31:06.070284Z","caller":"traceutil/trace.go:171","msg":"trace[397258885] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:18812; }","duration":"269.743635ms","start":"2026-10-02T19:31:05.800528Z","end":"2026-10-02T19:31:06.070271Z","steps":["trace[397258885] 'agreement among raft nodes before linearized reading' (duration: 269.558312ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:31:06.23879Z","caller":"traceutil/trace.go:171","msg":"trace[558763711] transaction","detail":"{read_only:false; response_revision:18813; number_of_response:1; }","duration":"435.086722ms","start":"2026-10-02T19:31:05.803663Z","end":"2026-10-02T19:31:06.238749Z","steps":["trace[558763711] 'process raft request' (duration: 423.552423ms)","trace[558763711] 'compare' (duration: 11.187432ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:31:06.238864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.989271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-02T19:31:06.238983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:31:05.803646Z","time spent":"435.219784ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:31:06.239137Z","caller":"traceutil/trace.go:171","msg":"trace[1944221900] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18813; }","duration":"269.129204ms","start":"2026-10-02T19:31:05.969799Z","end":"2026-10-02T19:31:06.238928Z","steps":["trace[1944221900] 'agreement among raft nodes before linearized reading' (duration: 268.943381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:31:06.239519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.627342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wld98\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-10-02T19:31:06.239568Z","caller":"traceutil/trace.go:171","msg":"trace[346538025] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wld98; range_end:; response_count:1; response_revision:18813; }","duration":"299.722334ms","start":"2026-10-02T19:31:05.939831Z","end":"2026-10-02T19:31:06.239553Z","steps":["trace[346538025] 'agreement among raft nodes before linearized reading' (duration: 299.567151ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:31:06.238616Z","caller":"traceutil/trace.go:171","msg":"trace[805009283] linearizableReadLoop","detail":"{readStateIndex:19470; appliedIndex:19469; }","duration":"168.507206ms","start":"2026-10-02T19:31:06.070055Z","end":"2026-10-02T19:31:06.238562Z","steps":["trace[805009283] 'read index received' (duration: 157.160991ms)","trace[805009283] 'applied index is now lower than readState.Index' (duration: 11.344115ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:31:06.240097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.235539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130517"} {"level":"info","ts":"2026-10-02T19:31:06.240132Z","caller":"traceutil/trace.go:171","msg":"trace[922365660] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:18813; }","duration":"382.32845ms","start":"2026-10-02T19:31:05.857794Z","end":"2026-10-02T19:31:06.240122Z","steps":["trace[922365660] 'agreement among raft nodes before linearized reading' (duration: 382.172238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:31:06.240157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:31:05.857764Z","time spent":"382.386251ms","remote":"127.0.0.1:47696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130539,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-10-02T19:31:06.243027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.941415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:74 size:102080"} {"level":"info","ts":"2026-10-02T19:31:06.243167Z","caller":"traceutil/trace.go:171","msg":"trace[73679345] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:74; response_revision:18813; }","duration":"168.18328ms","start":"2026-10-02T19:31:06.074957Z","end":"2026-10-02T19:31:06.24314Z","steps":["trace[73679345] 'agreement among raft nodes before linearized reading' (duration: 165.54956ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:31:14.338016Z","caller":"traceutil/trace.go:171","msg":"trace[623488971] transaction","detail":"{read_only:false; response_revision:18954; number_of_response:1; }","duration":"114.95076ms","start":"2026-10-02T19:31:14.223039Z","end":"2026-10-02T19:31:14.33799Z","steps":["trace[623488971] 'process raft request' (duration: 96.52308ms)","trace[623488971] 'compare' (duration: 18.247736ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:31:14.338116Z","caller":"traceutil/trace.go:171","msg":"trace[728889349] linearizableReadLoop","detail":"{readStateIndex:19615; appliedIndex:19614; }","duration":"114.375398ms","start":"2026-10-02T19:31:14.2237Z","end":"2026-10-02T19:31:14.338076Z","steps":["trace[728889349] 'read index received' (duration: 95.876617ms)","trace[728889349] 'applied index is now lower than readState.Index' (duration: 18.496641ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:31:14.338169Z","caller":"traceutil/trace.go:171","msg":"trace[2089932841] transaction","detail":"{read_only:false; number_of_response:1; response_revision:18954; }","duration":"109.596859ms","start":"2026-10-02T19:31:14.228552Z","end":"2026-10-02T19:31:14.338149Z","steps":["trace[2089932841] 'process raft request' (duration: 109.450116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:31:14.338344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.623723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19400"} {"level":"info","ts":"2026-10-02T19:31:14.338376Z","caller":"traceutil/trace.go:171","msg":"trace[308254173] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18956; }","duration":"114.690365ms","start":"2026-10-02T19:31:14.223675Z","end":"2026-10-02T19:31:14.338365Z","steps":["trace[308254173] 'agreement among raft nodes before linearized reading' (duration: 114.541592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:31:14.33836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.103038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4727"} {"level":"warn","ts":"2026-10-02T19:31:14.338518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.944945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-10-02T19:31:14.338554Z","caller":"traceutil/trace.go:171","msg":"trace[1684330276] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:18956; }","duration":"101.006945ms","start":"2026-10-02T19:31:14.237533Z","end":"2026-10-02T19:31:14.33854Z","steps":["trace[1684330276] 'agreement among raft nodes before linearized reading' (duration: 100.901303ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:31:14.338541Z","caller":"traceutil/trace.go:171","msg":"trace[843730310] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:18956; }","duration":"110.283351ms","start":"2026-10-02T19:31:14.228207Z","end":"2026-10-02T19:31:14.338491Z","steps":["trace[843730310] 'agreement among raft nodes before linearized reading' (duration: 110.033707ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:31:46.681155Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"43ce62d0a10d51da","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T19:31:46.68741Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-02T19:31:46.687549Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-02T19:32:04.251355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17000} {"level":"info","ts":"2026-10-02T19:32:04.351734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17000,"took":"97.216983ms","hash":2890885467,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":33267712,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-02T19:32:04.35181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2890885467,"revision":17000,"compact-revision":14609} {"level":"info","ts":"2026-10-02T19:37:04.259431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19475} {"level":"info","ts":"2026-10-02T19:37:04.378692Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19475,"took":"115.545661ms","hash":3199944173,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":29708288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-02T19:37:04.378772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3199944173,"revision":19475,"compact-revision":17000} {"level":"info","ts":"2026-10-02T19:37:07.874251Z","caller":"traceutil/trace.go:171","msg":"trace[1960065596] linearizableReadLoop","detail":"{readStateIndex:22326; appliedIndex:22325; }","duration":"154.395228ms","start":"2026-10-02T19:37:07.719831Z","end":"2026-10-02T19:37:07.874226Z","steps":["trace[1960065596] 'read index received' (duration: 154.169074ms)","trace[1960065596] 'applied index is now lower than readState.Index' (duration: 225.274µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:37:07.874335Z","caller":"traceutil/trace.go:171","msg":"trace[143608084] transaction","detail":"{read_only:false; response_revision:21578; number_of_response:1; }","duration":"198.317962ms","start":"2026-10-02T19:37:07.675982Z","end":"2026-10-02T19:37:07.8743Z","steps":["trace[143608084] 'process raft request' (duration: 198.072937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:37:07.874767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.851358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.213\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-02T19:37:07.874895Z","caller":"traceutil/trace.go:171","msg":"trace[2106651031] range","detail":"{range_begin:/registry/masterleases/162.253.55.213; range_end:; response_count:1; response_revision:21578; }","duration":"155.091772ms","start":"2026-10-02T19:37:07.719772Z","end":"2026-10-02T19:37:07.874863Z","steps":["trace[2106651031] 'agreement among raft nodes before linearized reading' (duration: 154.564611ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:37:13.363345Z","caller":"traceutil/trace.go:171","msg":"trace[893537746] transaction","detail":"{read_only:false; response_revision:21609; number_of_response:1; }","duration":"136.100191ms","start":"2026-10-02T19:37:13.227219Z","end":"2026-10-02T19:37:13.36332Z","steps":["trace[893537746] 'process raft request' (duration: 135.913307ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:37:14.119692Z","caller":"traceutil/trace.go:171","msg":"trace[654028052] transaction","detail":"{read_only:false; response_revision:21611; number_of_response:1; }","duration":"220.757797ms","start":"2026-10-02T19:37:13.898902Z","end":"2026-10-02T19:37:14.119659Z","steps":["trace[654028052] 'process raft request' (duration: 220.572974ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:37:14.79606Z","caller":"traceutil/trace.go:171","msg":"trace[1388150940] transaction","detail":"{read_only:false; response_revision:21614; number_of_response:1; }","duration":"144.92686ms","start":"2026-10-02T19:37:14.651109Z","end":"2026-10-02T19:37:14.796036Z","steps":["trace[1388150940] 'process raft request' (duration: 144.782847ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:37:14.909941Z","caller":"traceutil/trace.go:171","msg":"trace[1790030359] transaction","detail":"{read_only:false; response_revision:21615; number_of_response:1; }","duration":"258.762378ms","start":"2026-10-02T19:37:14.651147Z","end":"2026-10-02T19:37:14.909909Z","steps":["trace[1790030359] 'process raft request' (duration: 237.096617ms)","trace[1790030359] 'compare' (duration: 21.466647ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:37:14.910108Z","caller":"traceutil/trace.go:171","msg":"trace[1506737192] linearizableReadLoop","detail":"{readStateIndex:22366; appliedIndex:22364; }","duration":"207.975185ms","start":"2026-10-02T19:37:14.702092Z","end":"2026-10-02T19:37:14.910067Z","steps":["trace[1506737192] 'read index received' (duration: 93.973363ms)","trace[1506737192] 'applied index is now lower than readState.Index' (duration: 113.999772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:37:14.910337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.128918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:37:14.910417Z","caller":"traceutil/trace.go:171","msg":"trace[789356668] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:21616; }","duration":"208.340643ms","start":"2026-10-02T19:37:14.702056Z","end":"2026-10-02T19:37:14.910397Z","steps":["trace[789356668] 'agreement among raft nodes before linearized reading' (duration: 208.103888ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:37:14.910304Z","caller":"traceutil/trace.go:171","msg":"trace[1595194067] transaction","detail":"{read_only:false; response_revision:21616; number_of_response:1; }","duration":"257.876491ms","start":"2026-10-02T19:37:14.65235Z","end":"2026-10-02T19:37:14.910226Z","steps":["trace[1595194067] 'process raft request' (duration: 257.506334ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:41:28.32815Z","caller":"traceutil/trace.go:171","msg":"trace[137749318] transaction","detail":"{read_only:false; response_revision:24191; number_of_response:1; }","duration":"101.506605ms","start":"2026-10-02T19:41:28.226615Z","end":"2026-10-02T19:41:28.328122Z","steps":["trace[137749318] 'process raft request' (duration: 101.315921ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:41:28.43045Z","caller":"traceutil/trace.go:171","msg":"trace[399438751] transaction","detail":"{read_only:false; response_revision:24192; number_of_response:1; }","duration":"147.189433ms","start":"2026-10-02T19:41:28.28323Z","end":"2026-10-02T19:41:28.43042Z","steps":["trace[399438751] 'process raft request' (duration: 140.447094ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:41:28.430582Z","caller":"traceutil/trace.go:171","msg":"trace[1568401655] linearizableReadLoop","detail":"{readStateIndex:25017; appliedIndex:25015; }","duration":"107.370607ms","start":"2026-10-02T19:41:28.323178Z","end":"2026-10-02T19:41:28.430549Z","steps":["trace[1568401655] 'read index received' (duration: 4.832782ms)","trace[1568401655] 'applied index is now lower than readState.Index' (duration: 102.535005ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:41:28.430811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.614921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-02T19:41:28.430885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.22219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-02T19:41:28.430927Z","caller":"traceutil/trace.go:171","msg":"trace[1679780202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24193; }","duration":"101.370773ms","start":"2026-10-02T19:41:28.32954Z","end":"2026-10-02T19:41:28.430911Z","steps":["trace[1679780202] 'agreement among raft nodes before linearized reading' (duration: 101.164069ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:41:28.430922Z","caller":"traceutil/trace.go:171","msg":"trace[1457926899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24193; }","duration":"107.719433ms","start":"2026-10-02T19:41:28.32316Z","end":"2026-10-02T19:41:28.430879Z","steps":["trace[1457926899] 'agreement among raft nodes before linearized reading' (duration: 107.514389ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:42:04.270136Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21561} {"level":"info","ts":"2026-10-02T19:42:04.3903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21561,"took":"113.203808ms","hash":2795063586,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":42356736,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-02T19:42:04.390361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2795063586,"revision":21561,"compact-revision":19475} {"level":"info","ts":"2026-10-02T19:43:13.327863Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006623.wal"} {"level":"info","ts":"2026-10-02T19:44:26.0263Z","caller":"traceutil/trace.go:171","msg":"trace[1253517911] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"123.428151ms","start":"2026-10-02T19:44:25.902841Z","end":"2026-10-02T19:44:26.026269Z","steps":["trace[1253517911] 'process raft request' (duration: 123.226197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.139771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.444585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:04.13989Z","caller":"traceutil/trace.go:171","msg":"trace[984783680] linearizableReadLoop","detail":"{readStateIndex:27214; appliedIndex:27213; }","duration":"478.438964ms","start":"2026-10-02T19:45:03.661434Z","end":"2026-10-02T19:45:04.139873Z","steps":["trace[984783680] 'read index received' (duration: 125.833246ms)","trace[984783680] 'applied index is now lower than readState.Index' (duration: 352.604198ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:04.139965Z","caller":"traceutil/trace.go:171","msg":"trace[1904264045] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"480.436052ms","start":"2026-10-02T19:45:03.65949Z","end":"2026-10-02T19:45:04.139926Z","steps":["trace[1904264045] 'process raft request' (duration: 127.736352ms)","trace[1904264045] 'compare' (duration: 352.092398ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:04.140128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:03.659472Z","time spent":"480.569824ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:04.140121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.683349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:04.140227Z","caller":"traceutil/trace.go:171","msg":"trace[1219262668] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:26333; }","duration":"478.812831ms","start":"2026-10-02T19:45:03.6614Z","end":"2026-10-02T19:45:04.140212Z","steps":["trace[1219262668] 'agreement among raft nodes before linearized reading' (duration: 478.642698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.140305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:03.661388Z","time spent":"478.871952ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"warn","ts":"2026-10-02T19:45:04.735062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.114266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:04.735172Z","caller":"traceutil/trace.go:171","msg":"trace[1169152876] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"970.997667ms","start":"2026-10-02T19:45:03.764153Z","end":"2026-10-02T19:45:04.735151Z","steps":["trace[1169152876] 'process raft request' (duration: 549.696617ms)","trace[1169152876] 'compare' (duration: 420.886122ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:04.73523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:03.764128Z","time spent":"971.072328ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:04.736315Z","caller":"traceutil/trace.go:171","msg":"trace[1296774473] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"719.65875ms","start":"2026-10-02T19:45:04.016636Z","end":"2026-10-02T19:45:04.736294Z","steps":["trace[1296774473] 'process raft request' (duration: 719.544488ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:04.736314Z","caller":"traceutil/trace.go:171","msg":"trace[448286371] transaction","detail":"{read_only:false; response_revision:26336; number_of_response:1; }","duration":"904.843082ms","start":"2026-10-02T19:45:03.831447Z","end":"2026-10-02T19:45:04.736291Z","steps":["trace[448286371] 'process raft request' (duration: 904.692799ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:04.736326Z","caller":"traceutil/trace.go:171","msg":"trace[956004733] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"907.058364ms","start":"2026-10-02T19:45:03.82923Z","end":"2026-10-02T19:45:04.736288Z","steps":["trace[956004733] 'process raft request' (duration: 906.771059ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:04.736332Z","caller":"traceutil/trace.go:171","msg":"trace[610791440] linearizableReadLoop","detail":"{readStateIndex:27219; appliedIndex:27214; }","duration":"596.33626ms","start":"2026-10-02T19:45:04.139969Z","end":"2026-10-02T19:45:04.736305Z","steps":["trace[610791440] 'read index received' (duration: 173.887208ms)","trace[610791440] 'applied index is now lower than readState.Index' (duration: 422.447972ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:04.736353Z","caller":"traceutil/trace.go:171","msg":"trace[158745789] transaction","detail":"{read_only:false; response_revision:26339; number_of_response:1; }","duration":"472.353539ms","start":"2026-10-02T19:45:04.263973Z","end":"2026-10-02T19:45:04.736326Z","steps":["trace[158745789] 'process raft request' (duration: 472.288048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.736405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.016607Z","time spent":"719.749661ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:04.736379Z","caller":"traceutil/trace.go:171","msg":"trace[727473765] transaction","detail":"{read_only:false; response_revision:26338; number_of_response:1; }","duration":"717.646071ms","start":"2026-10-02T19:45:04.018665Z","end":"2026-10-02T19:45:04.736311Z","steps":["trace[727473765] 'process raft request' (duration: 717.55814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.736474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:03.829216Z","time spent":"907.175936ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:04.736441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:03.831428Z","time spent":"904.942775ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:04.736506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.263871Z","time spent":"472.545013ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:04.736462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.760332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:45:04.7365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033406101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-02T19:45:04.736555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065203264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"warn","ts":"2026-10-02T19:45:04.736543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.018626Z","time spent":"717.848696ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:04.736614Z","caller":"traceutil/trace.go:171","msg":"trace[887066347] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26339; }","duration":"1.065298086s","start":"2026-10-02T19:45:03.671304Z","end":"2026-10-02T19:45:04.736602Z","steps":["trace[887066347] 'agreement among raft nodes before linearized reading' (duration: 1.065118383s)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:04.736607Z","caller":"traceutil/trace.go:171","msg":"trace[938390404] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:26339; }","duration":"912.937065ms","start":"2026-10-02T19:45:03.823617Z","end":"2026-10-02T19:45:04.736554Z","steps":["trace[938390404] 'agreement among raft nodes before linearized reading' (duration: 912.69338ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:04.736615Z","caller":"traceutil/trace.go:171","msg":"trace[1578010844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26339; }","duration":"1.033543833s","start":"2026-10-02T19:45:03.703051Z","end":"2026-10-02T19:45:04.736594Z","steps":["trace[1578010844] 'agreement among raft nodes before linearized reading' (duration: 1.033266748s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:04.73665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:03.671294Z","time spent":"1.065347147s","remote":"127.0.0.1:47814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-02T19:45:04.736677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:03.703041Z","time spent":"1.033622384s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-02T19:45:04.736675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:03.823596Z","time spent":"913.061038ms","remote":"127.0.0.1:39676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-10-02T19:45:05.053928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.851949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:05.054108Z","caller":"traceutil/trace.go:171","msg":"trace[1783212148] transaction","detail":"{read_only:false; response_revision:26341; number_of_response:1; }","duration":"313.75996ms","start":"2026-10-02T19:45:04.74033Z","end":"2026-10-02T19:45:05.05409Z","steps":["trace[1783212148] 'process raft request' (duration: 313.697659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:05.054172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.740314Z","time spent":"313.824411ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:05.054184Z","caller":"traceutil/trace.go:171","msg":"trace[1524373817] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"458.886933ms","start":"2026-10-02T19:45:04.595251Z","end":"2026-10-02T19:45:05.054138Z","steps":["trace[1524373817] 'process raft request' (duration: 295.74238ms)","trace[1524373817] 'compare' (duration: 162.455631ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:05.054375Z","caller":"traceutil/trace.go:171","msg":"trace[996623340] linearizableReadLoop","detail":"{readStateIndex:27221; appliedIndex:27220; }","duration":"317.96298ms","start":"2026-10-02T19:45:04.7364Z","end":"2026-10-02T19:45:05.054363Z","steps":["trace[996623340] 'read index received' (duration: 154.609733ms)","trace[996623340] 'applied index is now lower than readState.Index' (duration: 163.351957ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:05.054484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.213853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:05.054517Z","caller":"traceutil/trace.go:171","msg":"trace[998058933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26341; }","duration":"476.276283ms","start":"2026-10-02T19:45:04.578232Z","end":"2026-10-02T19:45:05.054508Z","steps":["trace[998058933] 'agreement among raft nodes before linearized reading' (duration: 476.219812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:05.054543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.578197Z","time spent":"476.340475ms","remote":"127.0.0.1:47618","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-10-02T19:45:05.054744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.877881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"warn","ts":"2026-10-02T19:45:05.054365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.595222Z","time spent":"459.037206ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:05.054813Z","caller":"traceutil/trace.go:171","msg":"trace[264902135] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26341; }","duration":"314.970094ms","start":"2026-10-02T19:45:04.739825Z","end":"2026-10-02T19:45:05.054795Z","steps":["trace[264902135] 'agreement among raft nodes before linearized reading' (duration: 314.78673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:05.054845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.173552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-02T19:45:05.054855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.739806Z","time spent":"315.037345ms","remote":"127.0.0.1:47814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-02T19:45:05.054887Z","caller":"traceutil/trace.go:171","msg":"trace[1402864745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26341; }","duration":"330.240223ms","start":"2026-10-02T19:45:04.724634Z","end":"2026-10-02T19:45:05.054874Z","steps":["trace[1402864745] 'agreement among raft nodes before linearized reading' (duration: 330.107501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:05.054914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.724624Z","time spent":"330.283714ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T19:45:05.055905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.509185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:05.056013Z","caller":"traceutil/trace.go:171","msg":"trace[1559567566] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:26341; }","duration":"440.646188ms","start":"2026-10-02T19:45:04.615338Z","end":"2026-10-02T19:45:05.055984Z","steps":["trace[1559567566] 'agreement among raft nodes before linearized reading' (duration: 439.083508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:05.05617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:04.615327Z","time spent":"440.7849ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true "} {"level":"warn","ts":"2026-10-02T19:45:05.371838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.036487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:05.372007Z","caller":"traceutil/trace.go:171","msg":"trace[1913832406] linearizableReadLoop","detail":"{readStateIndex:27223; appliedIndex:27222; }","duration":"231.967889ms","start":"2026-10-02T19:45:05.140019Z","end":"2026-10-02T19:45:05.371987Z","steps":["trace[1913832406] 'read index received' (duration: 72.736899ms)","trace[1913832406] 'applied index is now lower than readState.Index' (duration: 159.2298ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:05.372074Z","caller":"traceutil/trace.go:171","msg":"trace[1377037792] transaction","detail":"{read_only:false; response_revision:26342; number_of_response:1; }","duration":"310.200413ms","start":"2026-10-02T19:45:05.061838Z","end":"2026-10-02T19:45:05.372038Z","steps":["trace[1377037792] 'process raft request' (duration: 150.890602ms)","trace[1377037792] 'compare' (duration: 158.73512ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:05.372116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.083731ms","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-02T19:45:05.372195Z","caller":"traceutil/trace.go:171","msg":"trace[1861103328] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26342; }","duration":"232.205594ms","start":"2026-10-02T19:45:05.139969Z","end":"2026-10-02T19:45:05.372175Z","steps":["trace[1861103328] 'agreement among raft nodes before linearized reading' (duration: 232.065061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:05.372216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:05.06182Z","time spent":"310.318446ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:05.697102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.946585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:05.697219Z","caller":"traceutil/trace.go:171","msg":"trace[1484650762] linearizableReadLoop","detail":"{readStateIndex:27224; appliedIndex:27223; }","duration":"325.173638ms","start":"2026-10-02T19:45:05.372023Z","end":"2026-10-02T19:45:05.697197Z","steps":["trace[1484650762] 'read index received' (duration: 136.963798ms)","trace[1484650762] 'applied index is now lower than readState.Index' (duration: 188.20826ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:05.69748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.876683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:05.697457Z","caller":"traceutil/trace.go:171","msg":"trace[414842658] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"401.682379ms","start":"2026-10-02T19:45:05.295741Z","end":"2026-10-02T19:45:05.697423Z","steps":["trace[414842658] 'process raft request' (duration: 213.313846ms)","trace[414842658] 'compare' (duration: 187.802202ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:05.69749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.031559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:45:05.6975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.13732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:05.697559Z","caller":"traceutil/trace.go:171","msg":"trace[909129268] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26343; }","duration":"508.016095ms","start":"2026-10-02T19:45:05.189529Z","end":"2026-10-02T19:45:05.697545Z","steps":["trace[909129268] 'agreement among raft nodes before linearized reading' (duration: 507.893523ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:05.69761Z","caller":"traceutil/trace.go:171","msg":"trace[1350684750] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:26343; }","duration":"205.150661ms","start":"2026-10-02T19:45:05.492435Z","end":"2026-10-02T19:45:05.697585Z","steps":["trace[1350684750] 'agreement among raft nodes before linearized reading' (duration: 205.000828ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:05.697617Z","caller":"traceutil/trace.go:171","msg":"trace[1133000362] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26343; }","duration":"322.308143ms","start":"2026-10-02T19:45:05.37529Z","end":"2026-10-02T19:45:05.697598Z","steps":["trace[1133000362] 'agreement among raft nodes before linearized reading' (duration: 322.14062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:05.697631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:05.189478Z","time spent":"508.140858ms","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-10-02T19:45:05.697648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.33355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-02T19:45:05.697669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:05.375272Z","time spent":"322.383694ms","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-02T19:45:05.697678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:05.295714Z","time spent":"401.871522ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:05.697704Z","caller":"traceutil/trace.go:171","msg":"trace[1107891484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26343; }","duration":"132.389631ms","start":"2026-10-02T19:45:05.5653Z","end":"2026-10-02T19:45:05.697689Z","steps":["trace[1107891484] 'agreement among raft nodes before linearized reading' (duration: 132.132076ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:06.022713Z","caller":"traceutil/trace.go:171","msg":"trace[981310416] linearizableReadLoop","detail":"{readStateIndex:27226; appliedIndex:27225; }","duration":"318.481671ms","start":"2026-10-02T19:45:05.704207Z","end":"2026-10-02T19:45:06.022689Z","steps":["trace[981310416] 'read index received' (duration: 79.770983ms)","trace[981310416] 'applied index is now lower than readState.Index' (duration: 238.709558ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:06.022824Z","caller":"traceutil/trace.go:171","msg":"trace[938583884] transaction","detail":"{read_only:false; response_revision:26345; number_of_response:1; }","duration":"319.801035ms","start":"2026-10-02T19:45:05.703011Z","end":"2026-10-02T19:45:06.022812Z","steps":["trace[938583884] 'process raft request' (duration: 319.50225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:06.022933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:05.702987Z","time spent":"319.864437ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:06.023027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.946738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:06.023106Z","caller":"traceutil/trace.go:171","msg":"trace[544769459] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26345; }","duration":"311.07474ms","start":"2026-10-02T19:45:05.712011Z","end":"2026-10-02T19:45:06.023086Z","steps":["trace[544769459] 'agreement among raft nodes before linearized reading' (duration: 310.928747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:06.023152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:05.712002Z","time spent":"311.140731ms","remote":"127.0.0.1:47888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-10-02T19:45:06.023364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.859892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:06.023399Z","caller":"traceutil/trace.go:171","msg":"trace[830708149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26345; }","duration":"208.923372ms","start":"2026-10-02T19:45:05.814466Z","end":"2026-10-02T19:45:06.02339Z","steps":["trace[830708149] 'agreement among raft nodes before linearized reading' (duration: 208.868481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:06.023558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.196392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:06.023624Z","caller":"traceutil/trace.go:171","msg":"trace[77784242] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:26345; }","duration":"263.302325ms","start":"2026-10-02T19:45:05.760313Z","end":"2026-10-02T19:45:06.023615Z","steps":["trace[77784242] 'agreement among raft nodes before linearized reading' (duration: 263.213583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:06.023717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.944637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274725"} {"level":"info","ts":"2026-10-02T19:45:06.023767Z","caller":"traceutil/trace.go:171","msg":"trace[1262497073] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26345; }","duration":"176.016088ms","start":"2026-10-02T19:45:05.847739Z","end":"2026-10-02T19:45:06.023755Z","steps":["trace[1262497073] 'agreement among raft nodes before linearized reading' (duration: 175.533929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:06.024067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.855277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"info","ts":"2026-10-02T19:45:06.024105Z","caller":"traceutil/trace.go:171","msg":"trace[879238031] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26345; }","duration":"319.919729ms","start":"2026-10-02T19:45:05.704177Z","end":"2026-10-02T19:45:06.024096Z","steps":["trace[879238031] 'agreement among raft nodes before linearized reading' (duration: 319.812157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:06.02413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:05.704166Z","time spent":"319.956899ms","remote":"127.0.0.1:47812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19049,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-02T19:45:06.509493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.440117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:06.509641Z","caller":"traceutil/trace.go:171","msg":"trace[914469397] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"307.452343ms","start":"2026-10-02T19:45:06.202159Z","end":"2026-10-02T19:45:06.509612Z","steps":["trace[914469397] 'process raft request' (duration: 121.808182ms)","trace[914469397] 'compare' (duration: 185.251423ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:06.509891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:06.202135Z","time spent":"307.673886ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:06.923048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.736183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:45:06.923134Z","caller":"traceutil/trace.go:171","msg":"trace[345188400] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:26347; }","duration":"204.883666ms","start":"2026-10-02T19:45:06.71823Z","end":"2026-10-02T19:45:06.923113Z","steps":["trace[345188400] 'count revisions from in-memory index tree' (duration: 204.594141ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:07.184946Z","caller":"traceutil/trace.go:171","msg":"trace[669521996] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"444.00268ms","start":"2026-10-02T19:45:06.74092Z","end":"2026-10-02T19:45:07.184922Z","steps":["trace[669521996] 'process raft request' (duration: 443.835197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:07.185098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:06.740903Z","time spent":"444.116032ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:07.495997Z","caller":"traceutil/trace.go:171","msg":"trace[1119847451] linearizableReadLoop","detail":"{readStateIndex:27234; appliedIndex:27228; }","duration":"679.036078ms","start":"2026-10-02T19:45:06.816939Z","end":"2026-10-02T19:45:07.495975Z","steps":["trace[1119847451] 'read index received' (duration: 367.948808ms)","trace[1119847451] 'applied index is now lower than readState.Index' (duration: 311.08614ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:07.496343Z","caller":"traceutil/trace.go:171","msg":"trace[1774366248] transaction","detail":"{read_only:false; response_revision:26349; number_of_response:1; }","duration":"755.247005ms","start":"2026-10-02T19:45:06.741051Z","end":"2026-10-02T19:45:07.496298Z","steps":["trace[1774366248] 'process raft request' (duration: 754.607322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:07.496447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:06.741046Z","time spent":"755.330746ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:07.496554Z","caller":"traceutil/trace.go:171","msg":"trace[2038798927] transaction","detail":"{read_only:false; response_revision:26350; number_of_response:1; }","duration":"754.036522ms","start":"2026-10-02T19:45:06.742509Z","end":"2026-10-02T19:45:07.496545Z","steps":["trace[2038798927] 'process raft request' (duration: 753.292657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:07.496628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:06.742482Z","time spent":"754.111333ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:07.496738Z","caller":"traceutil/trace.go:171","msg":"trace[2031245529] transaction","detail":"{read_only:false; response_revision:26354; number_of_response:1; }","duration":"436.51295ms","start":"2026-10-02T19:45:07.060194Z","end":"2026-10-02T19:45:07.496707Z","steps":["trace[2031245529] 'process raft request' (duration: 435.745345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:07.496791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.846454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:07.496826Z","caller":"traceutil/trace.go:171","msg":"trace[505731965] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26354; }","duration":"679.919996ms","start":"2026-10-02T19:45:06.816894Z","end":"2026-10-02T19:45:07.496814Z","steps":["trace[505731965] 'agreement among raft nodes before linearized reading' (duration: 679.858475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:07.496862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:06.816873Z","time spent":"679.980707ms","remote":"127.0.0.1:35736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-02T19:45:07.496896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:07.060171Z","time spent":"436.640552ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:07.497109Z","caller":"traceutil/trace.go:171","msg":"trace[1993032208] transaction","detail":"{read_only:false; response_revision:26351; number_of_response:1; }","duration":"754.45734ms","start":"2026-10-02T19:45:06.742639Z","end":"2026-10-02T19:45:07.497097Z","steps":["trace[1993032208] 'process raft request' (duration: 753.200566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:07.497172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:06.742631Z","time spent":"754.511511ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:07.497258Z","caller":"traceutil/trace.go:171","msg":"trace[622499233] transaction","detail":"{read_only:false; response_revision:26352; number_of_response:1; }","duration":"754.46856ms","start":"2026-10-02T19:45:06.742779Z","end":"2026-10-02T19:45:07.497248Z","steps":["trace[622499233] 'process raft request' (duration: 753.090443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:07.497302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:06.742768Z","time spent":"754.508291ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:07.497435Z","caller":"traceutil/trace.go:171","msg":"trace[77666381] transaction","detail":"{read_only:false; response_revision:26353; number_of_response:1; }","duration":"754.511842ms","start":"2026-10-02T19:45:06.742914Z","end":"2026-10-02T19:45:07.497426Z","steps":["trace[77666381] 'process raft request' (duration: 752.993272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:07.497541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:06.742906Z","time spent":"754.555552ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:07.497116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.341885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T19:45:07.497751Z","caller":"traceutil/trace.go:171","msg":"trace[330678986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26354; }","duration":"439.008348ms","start":"2026-10-02T19:45:07.05873Z","end":"2026-10-02T19:45:07.497739Z","steps":["trace[330678986] 'agreement among raft nodes before linearized reading' (duration: 438.288584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:07.497806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:07.05871Z","time spent":"439.071579ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-02T19:45:07.498088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.607156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-02T19:45:07.4982Z","caller":"traceutil/trace.go:171","msg":"trace[622227657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26354; }","duration":"121.753199ms","start":"2026-10-02T19:45:07.376425Z","end":"2026-10-02T19:45:07.498178Z","steps":["trace[622227657] 'agreement among raft nodes before linearized reading' (duration: 121.466334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:07.498444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.384847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:07.49849Z","caller":"traceutil/trace.go:171","msg":"trace[1983249556] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:26354; }","duration":"136.472568ms","start":"2026-10-02T19:45:07.362003Z","end":"2026-10-02T19:45:07.498475Z","steps":["trace[1983249556] 'agreement among raft nodes before linearized reading' (duration: 136.390956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:12.70718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.486797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:12.707263Z","caller":"traceutil/trace.go:171","msg":"trace[107859201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26403; }","duration":"128.603839ms","start":"2026-10-02T19:45:12.578633Z","end":"2026-10-02T19:45:12.707237Z","steps":["trace[107859201] 'range keys from in-memory index tree' (duration: 128.427687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:12.707474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.096626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-02T19:45:12.707606Z","caller":"traceutil/trace.go:171","msg":"trace[438171814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26403; }","duration":"151.240778ms","start":"2026-10-02T19:45:12.556304Z","end":"2026-10-02T19:45:12.707545Z","steps":["trace[438171814] 'range keys from in-memory index tree' (duration: 150.893392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:12.936389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.191357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419144"} {"level":"info","ts":"2026-10-02T19:45:12.936538Z","caller":"traceutil/trace.go:171","msg":"trace[1598587022] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26404; }","duration":"113.37144ms","start":"2026-10-02T19:45:12.823117Z","end":"2026-10-02T19:45:12.936488Z","steps":["trace[1598587022] 'range keys from in-memory index tree' (duration: 112.8122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:13.289417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.942002ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-02T19:45:13.309104Z","caller":"traceutil/trace.go:171","msg":"trace[384035782] transaction","detail":"{read_only:false; response_revision:26405; number_of_response:1; }","duration":"100.033488ms","start":"2026-10-02T19:45:13.209045Z","end":"2026-10-02T19:45:13.309079Z","steps":["trace[384035782] 'process raft request' (duration: 99.829114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:13.594807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.478357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:45:13.594881Z","caller":"traceutil/trace.go:171","msg":"trace[411925010] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26405; }","duration":"119.6264ms","start":"2026-10-02T19:45:13.475239Z","end":"2026-10-02T19:45:13.594866Z","steps":["trace[411925010] 'count revisions from in-memory index tree' (duration: 119.400045ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:14.010036Z","caller":"traceutil/trace.go:171","msg":"trace[1082273734] transaction","detail":"{read_only:false; response_revision:26407; number_of_response:1; }","duration":"463.217215ms","start":"2026-10-02T19:45:13.546795Z","end":"2026-10-02T19:45:14.010012Z","steps":["trace[1082273734] 'process raft request' (duration: 463.026882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:14.010268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:13.546779Z","time spent":"463.414339ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:14.010282Z","caller":"traceutil/trace.go:171","msg":"trace[253070186] transaction","detail":"{read_only:false; response_revision:26408; number_of_response:1; }","duration":"438.864864ms","start":"2026-10-02T19:45:13.571399Z","end":"2026-10-02T19:45:14.010264Z","steps":["trace[253070186] 'process raft request' (duration: 438.569658ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:14.010385Z","caller":"traceutil/trace.go:171","msg":"trace[1827685005] transaction","detail":"{read_only:false; response_revision:26409; number_of_response:1; }","duration":"438.598969ms","start":"2026-10-02T19:45:13.571777Z","end":"2026-10-02T19:45:14.010376Z","steps":["trace[1827685005] 'process raft request' (duration: 438.406616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:14.010465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:13.571762Z","time spent":"438.6588ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:14.010489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:13.571374Z","time spent":"439.016797ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:14.010744Z","caller":"traceutil/trace.go:171","msg":"trace[2059444458] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"438.780222ms","start":"2026-10-02T19:45:13.571944Z","end":"2026-10-02T19:45:14.010724Z","steps":["trace[2059444458] 'process raft request' (duration: 438.394324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:14.010811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:13.571926Z","time spent":"438.852124ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:14.011263Z","caller":"traceutil/trace.go:171","msg":"trace[1294525925] linearizableReadLoop","detail":"{readStateIndex:27295; appliedIndex:27289; }","duration":"371.270641ms","start":"2026-10-02T19:45:13.639981Z","end":"2026-10-02T19:45:14.011252Z","steps":["trace[1294525925] 'read index received' (duration: 369.890434ms)","trace[1294525925] 'applied index is now lower than readState.Index' (duration: 1.379327ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:14.011326Z","caller":"traceutil/trace.go:171","msg":"trace[1576323538] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"439.140799ms","start":"2026-10-02T19:45:13.572177Z","end":"2026-10-02T19:45:14.011317Z","steps":["trace[1576323538] 'process raft request' (duration: 438.494237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:14.011377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:13.572167Z","time spent":"439.1755ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:14.011461Z","caller":"traceutil/trace.go:171","msg":"trace[1328232105] transaction","detail":"{read_only:false; response_revision:26412; number_of_response:1; }","duration":"437.465858ms","start":"2026-10-02T19:45:13.573979Z","end":"2026-10-02T19:45:14.011445Z","steps":["trace[1328232105] 'process raft request' (duration: 436.827956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:14.011645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:13.57396Z","time spent":"437.62063ms","remote":"127.0.0.1:57546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419277,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:14.011667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.66757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T19:45:14.011948Z","caller":"traceutil/trace.go:171","msg":"trace[991674302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26412; }","duration":"371.992955ms","start":"2026-10-02T19:45:13.639942Z","end":"2026-10-02T19:45:14.011935Z","steps":["trace[991674302] 'agreement among raft nodes before linearized reading' (duration: 371.578797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:14.012061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:13.639927Z","time spent":"372.120338ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-02T19:45:14.023996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.510099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-02T19:45:14.024092Z","caller":"traceutil/trace.go:171","msg":"trace[1210388410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26414; }","duration":"369.636241ms","start":"2026-10-02T19:45:13.654434Z","end":"2026-10-02T19:45:14.02407Z","steps":["trace[1210388410] 'agreement among raft nodes before linearized reading' (duration: 369.380276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:14.024139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:13.654423Z","time spent":"369.705762ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-02T19:45:14.024143Z","caller":"traceutil/trace.go:171","msg":"trace[2064018591] transaction","detail":"{read_only:false; response_revision:26413; number_of_response:1; }","duration":"299.038942ms","start":"2026-10-02T19:45:13.725075Z","end":"2026-10-02T19:45:14.024114Z","steps":["trace[2064018591] 'process raft request' (duration: 298.512372ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:14.024322Z","caller":"traceutil/trace.go:171","msg":"trace[359350736] transaction","detail":"{read_only:false; response_revision:26414; number_of_response:1; }","duration":"261.760935ms","start":"2026-10-02T19:45:13.762516Z","end":"2026-10-02T19:45:14.024277Z","steps":["trace[359350736] 'process raft request' (duration: 261.231934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:14.02445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.554011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-02T19:45:14.0245Z","caller":"traceutil/trace.go:171","msg":"trace[1609598764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26414; }","duration":"202.652633ms","start":"2026-10-02T19:45:13.821833Z","end":"2026-10-02T19:45:14.024486Z","steps":["trace[1609598764] 'agreement among raft nodes before linearized reading' (duration: 202.461989ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:14.391606Z","caller":"traceutil/trace.go:171","msg":"trace[690247897] linearizableReadLoop","detail":"{readStateIndex:27300; appliedIndex:27298; }","duration":"330.281245ms","start":"2026-10-02T19:45:14.061304Z","end":"2026-10-02T19:45:14.391585Z","steps":["trace[690247897] 'read index received' (duration: 321.212463ms)","trace[690247897] 'applied index is now lower than readState.Index' (duration: 9.067632ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:14.391726Z","caller":"traceutil/trace.go:171","msg":"trace[1605407567] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"363.373442ms","start":"2026-10-02T19:45:14.02832Z","end":"2026-10-02T19:45:14.391694Z","steps":["trace[1605407567] 'process raft request' (duration: 354.29496ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:14.391744Z","caller":"traceutil/trace.go:171","msg":"trace[732631751] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"362.523265ms","start":"2026-10-02T19:45:14.029193Z","end":"2026-10-02T19:45:14.391716Z","steps":["trace[732631751] 'process raft request' (duration: 362.278441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:14.391848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.525239ms","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-02T19:45:14.391883Z","caller":"traceutil/trace.go:171","msg":"trace[1197257003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26417; }","duration":"330.60373ms","start":"2026-10-02T19:45:14.061269Z","end":"2026-10-02T19:45:14.391873Z","steps":["trace[1197257003] 'agreement among raft nodes before linearized reading' (duration: 330.432697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:14.391884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:14.02917Z","time spent":"362.637958ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:14.391886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:14.028294Z","time spent":"363.506505ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:14.39191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:14.061257Z","time spent":"330.646292ms","remote":"127.0.0.1:47874","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-02T19:45:16.116876Z","caller":"traceutil/trace.go:171","msg":"trace[1933119589] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"101.180979ms","start":"2026-10-02T19:45:16.01567Z","end":"2026-10-02T19:45:16.116851Z","steps":["trace[1933119589] 'process raft request' (duration: 100.985205ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:16.281338Z","caller":"traceutil/trace.go:171","msg":"trace[1100841025] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"265.353573ms","start":"2026-10-02T19:45:16.015949Z","end":"2026-10-02T19:45:16.281302Z","steps":["trace[1100841025] 'process raft request' (duration: 264.991456ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:16.281352Z","caller":"traceutil/trace.go:171","msg":"trace[611844769] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"265.065777ms","start":"2026-10-02T19:45:16.016252Z","end":"2026-10-02T19:45:16.281317Z","steps":["trace[611844769] 'process raft request' (duration: 264.951615ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:16.2816Z","caller":"traceutil/trace.go:171","msg":"trace[1380204573] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"264.758122ms","start":"2026-10-02T19:45:16.016784Z","end":"2026-10-02T19:45:16.281543Z","steps":["trace[1380204573] 'process raft request' (duration: 264.478716ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:16.281652Z","caller":"traceutil/trace.go:171","msg":"trace[2117694357] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"264.800842ms","start":"2026-10-02T19:45:16.016832Z","end":"2026-10-02T19:45:16.281633Z","steps":["trace[2117694357] 'process raft request' (duration: 264.645469ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:16.28173Z","caller":"traceutil/trace.go:171","msg":"trace[321418907] linearizableReadLoop","detail":"{readStateIndex:27312; appliedIndex:27306; }","duration":"247.997875ms","start":"2026-10-02T19:45:16.033716Z","end":"2026-10-02T19:45:16.281714Z","steps":["trace[321418907] 'read index received' (duration: 82.937594ms)","trace[321418907] 'applied index is now lower than readState.Index' (duration: 165.059021ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:16.281835Z","caller":"traceutil/trace.go:171","msg":"trace[623846257] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"253.07499ms","start":"2026-10-02T19:45:16.028717Z","end":"2026-10-02T19:45:16.281792Z","steps":["trace[623846257] 'process raft request' (duration: 252.871106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:16.281948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.213729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T19:45:16.281996Z","caller":"traceutil/trace.go:171","msg":"trace[210352703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26429; }","duration":"248.29957ms","start":"2026-10-02T19:45:16.033685Z","end":"2026-10-02T19:45:16.281985Z","steps":["trace[210352703] 'agreement among raft nodes before linearized reading' (duration: 248.106526ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:16.322475Z","caller":"traceutil/trace.go:171","msg":"trace[1260972651] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"104.42134ms","start":"2026-10-02T19:45:16.218031Z","end":"2026-10-02T19:45:16.322453Z","steps":["trace[1260972651] 'process raft request' (duration: 104.258637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:16.322623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.717897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:16.322704Z","caller":"traceutil/trace.go:171","msg":"trace[1873469203] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26430; }","duration":"277.890771ms","start":"2026-10-02T19:45:16.044792Z","end":"2026-10-02T19:45:16.322683Z","steps":["trace[1873469203] 'agreement among raft nodes before linearized reading' (duration: 277.635236ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:16.322758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.788089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-10-02T19:45:16.322824Z","caller":"traceutil/trace.go:171","msg":"trace[949718866] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26430; }","duration":"122.862931ms","start":"2026-10-02T19:45:16.199943Z","end":"2026-10-02T19:45:16.322806Z","steps":["trace[949718866] 'agreement among raft nodes before linearized reading' (duration: 122.659107ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:25.946051Z","caller":"traceutil/trace.go:171","msg":"trace[575140289] linearizableReadLoop","detail":"{readStateIndex:27390; appliedIndex:27389; }","duration":"130.486116ms","start":"2026-10-02T19:45:25.815543Z","end":"2026-10-02T19:45:25.946029Z","steps":["trace[575140289] 'read index received' (duration: 130.275522ms)","trace[575140289] 'applied index is now lower than readState.Index' (duration: 209.484µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:25.946126Z","caller":"traceutil/trace.go:171","msg":"trace[1432161312] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"276.417414ms","start":"2026-10-02T19:45:25.669672Z","end":"2026-10-02T19:45:25.946089Z","steps":["trace[1432161312] 'process raft request' (duration: 276.207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:25.946256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.607151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-02T19:45:25.946223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.662899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:25.946304Z","caller":"traceutil/trace.go:171","msg":"trace[1120706878] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:26505; }","duration":"107.673513ms","start":"2026-10-02T19:45:25.838615Z","end":"2026-10-02T19:45:25.946288Z","steps":["trace[1120706878] 'agreement among raft nodes before linearized reading' (duration: 107.54645ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:25.946324Z","caller":"traceutil/trace.go:171","msg":"trace[1357797843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26505; }","duration":"130.828772ms","start":"2026-10-02T19:45:25.815475Z","end":"2026-10-02T19:45:25.946304Z","steps":["trace[1357797843] 'agreement among raft nodes before linearized reading' (duration: 130.648328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:26.356264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.508706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:26.356699Z","caller":"traceutil/trace.go:171","msg":"trace[316306471] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:26505; }","duration":"303.976305ms","start":"2026-10-02T19:45:26.052675Z","end":"2026-10-02T19:45:26.356651Z","steps":["trace[316306471] 'count revisions from in-memory index tree' (duration: 303.444105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:26.356746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.052654Z","time spent":"304.077177ms","remote":"127.0.0.1:39658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"info","ts":"2026-10-02T19:45:26.356776Z","caller":"traceutil/trace.go:171","msg":"trace[1244415663] transaction","detail":"{read_only:false; response_revision:26506; number_of_response:1; }","duration":"191.211037ms","start":"2026-10-02T19:45:26.16554Z","end":"2026-10-02T19:45:26.356751Z","steps":["trace[1244415663] 'process raft request' (duration: 111.296371ms)","trace[1244415663] 'compare' (duration: 79.692701ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:26.855493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898203674602074187,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-02T19:45:26.939959Z","caller":"traceutil/trace.go:171","msg":"trace[1469273546] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"617.665626ms","start":"2026-10-02T19:45:26.322258Z","end":"2026-10-02T19:45:26.939924Z","steps":["trace[1469273546] 'process raft request' (duration: 617.468752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:26.94021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.322219Z","time spent":"617.842369ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:27.705069Z","caller":"traceutil/trace.go:171","msg":"trace[1497490586] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"1.380012635s","start":"2026-10-02T19:45:26.325025Z","end":"2026-10-02T19:45:27.705038Z","steps":["trace[1497490586] 'process raft request' (duration: 1.355024261s)","trace[1497490586] 'compare' (duration: 24.694859ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:27.70511Z","caller":"traceutil/trace.go:171","msg":"trace[1058195207] transaction","detail":"{read_only:false; response_revision:26510; number_of_response:1; }","duration":"1.373913749s","start":"2026-10-02T19:45:26.331169Z","end":"2026-10-02T19:45:27.705083Z","steps":["trace[1058195207] 'process raft request' (duration: 1.373840907s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.705215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.32499Z","time spent":"1.380148147s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:27.705248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.331142Z","time spent":"1.37402506s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:27.705292Z","caller":"traceutil/trace.go:171","msg":"trace[89919697] transaction","detail":"{read_only:false; response_revision:26511; number_of_response:1; }","duration":"1.371636935s","start":"2026-10-02T19:45:26.333638Z","end":"2026-10-02T19:45:27.705275Z","steps":["trace[89919697] 'process raft request' (duration: 1.371410171s)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:27.705373Z","caller":"traceutil/trace.go:171","msg":"trace[728944341] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"1.378290011s","start":"2026-10-02T19:45:26.327072Z","end":"2026-10-02T19:45:27.705362Z","steps":["trace[728944341] 'process raft request' (duration: 1.377829243s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.705431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.327046Z","time spent":"1.378350323s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:27.70537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.333613Z","time spent":"1.371717277s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:27.705556Z","caller":"traceutil/trace.go:171","msg":"trace[194945724] transaction","detail":"{read_only:false; response_revision:26512; number_of_response:1; }","duration":"1.36500432s","start":"2026-10-02T19:45:26.340541Z","end":"2026-10-02T19:45:27.705545Z","steps":["trace[194945724] 'process raft request' (duration: 1.364671893s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.705646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.340524Z","time spent":"1.365081731s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:27.70582Z","caller":"traceutil/trace.go:171","msg":"trace[1472454679] linearizableReadLoop","detail":"{readStateIndex:27398; appliedIndex:27391; }","duration":"1.350691117s","start":"2026-10-02T19:45:26.355102Z","end":"2026-10-02T19:45:27.705794Z","steps":["trace[1472454679] 'read index received' (duration: 584.611718ms)","trace[1472454679] 'applied index is now lower than readState.Index' (duration: 766.077499ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:27.705856Z","caller":"traceutil/trace.go:171","msg":"trace[1979048661] transaction","detail":"{read_only:false; response_revision:26513; number_of_response:1; }","duration":"1.30177033s","start":"2026-10-02T19:45:26.404058Z","end":"2026-10-02T19:45:27.705828Z","steps":["trace[1979048661] 'process raft request' (duration: 1.301421063s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.705997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.404024Z","time spent":"1.301893973s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:27.706034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350926503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:27.706084Z","caller":"traceutil/trace.go:171","msg":"trace[943976492] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26513; }","duration":"1.351019054s","start":"2026-10-02T19:45:26.35505Z","end":"2026-10-02T19:45:27.706069Z","steps":["trace[943976492] 'agreement among raft nodes before linearized reading' (duration: 1.35083075s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:27.706118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.355034Z","time spent":"1.351075585s","remote":"127.0.0.1:32790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-10-02T19:45:28.371637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.137786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:28.371777Z","caller":"traceutil/trace.go:171","msg":"trace[678921217] transaction","detail":"{read_only:false; response_revision:26514; number_of_response:1; }","duration":"1.014856418s","start":"2026-10-02T19:45:27.356872Z","end":"2026-10-02T19:45:28.371729Z","steps":["trace[678921217] 'process raft request' (duration: 456.56828ms)","trace[678921217] 'compare' (duration: 557.990482ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:28.371819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.356844Z","time spent":"1.014956831s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:28.371954Z","caller":"traceutil/trace.go:171","msg":"trace[1751583909] transaction","detail":"{read_only:false; response_revision:26515; number_of_response:1; }","duration":"886.662457ms","start":"2026-10-02T19:45:27.485262Z","end":"2026-10-02T19:45:28.371925Z","steps":["trace[1751583909] 'process raft request' (duration: 886.436533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.37209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.485236Z","time spent":"886.78194ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:28.372214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001057583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T19:45:28.372234Z","caller":"traceutil/trace.go:171","msg":"trace[602257479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26515; }","duration":"2.001135545s","start":"2026-10-02T19:45:26.371093Z","end":"2026-10-02T19:45:28.372229Z","steps":["trace[602257479] 'agreement among raft nodes before linearized reading' (duration: 2.001021963s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.372247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.371061Z","time spent":"2.001183096s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-02T19:45:28.372103Z","caller":"traceutil/trace.go:171","msg":"trace[355572744] linearizableReadLoop","detail":"{readStateIndex:27400; appliedIndex:27398; }","duration":"666.229506ms","start":"2026-10-02T19:45:27.705866Z","end":"2026-10-02T19:45:28.372095Z","steps":["trace[355572744] 'read index received' (duration: 107.552ms)","trace[355572744] 'applied index is now lower than readState.Index' (duration: 558.676346ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:28.372318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.213129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:28.372351Z","caller":"traceutil/trace.go:171","msg":"trace[1184301061] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:26515; }","duration":"847.26254ms","start":"2026-10-02T19:45:27.525068Z","end":"2026-10-02T19:45:28.372331Z","steps":["trace[1184301061] 'agreement among raft nodes before linearized reading' (duration: 847.228399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.372367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.525055Z","time spent":"847.30541ms","remote":"127.0.0.1:53282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-10-02T19:45:28.372507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.869024909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-02T19:45:28.372524Z","caller":"traceutil/trace.go:171","msg":"trace[116659956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26515; }","duration":"1.86908877s","start":"2026-10-02T19:45:26.503431Z","end":"2026-10-02T19:45:28.37252Z","steps":["trace[116659956] 'agreement among raft nodes before linearized reading' (duration: 1.869038699s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.372536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.503409Z","time spent":"1.869124372s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-02T19:45:28.372624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.909041179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-02T19:45:28.372654Z","caller":"traceutil/trace.go:171","msg":"trace[1213105323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26515; }","duration":"1.909130201s","start":"2026-10-02T19:45:26.463505Z","end":"2026-10-02T19:45:28.372635Z","steps":["trace[1213105323] 'agreement among raft nodes before linearized reading' (duration: 1.90908262s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.372678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.463477Z","time spent":"1.909197271s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-02T19:45:28.372774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912623117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-02T19:45:28.372789Z","caller":"traceutil/trace.go:171","msg":"trace[1913053960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26515; }","duration":"1.912687139s","start":"2026-10-02T19:45:26.460098Z","end":"2026-10-02T19:45:28.372785Z","steps":["trace[1913053960] 'agreement among raft nodes before linearized reading' (duration: 1.912629467s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.3728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.460077Z","time spent":"1.912720529s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T19:45:28.372826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.519648353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-02T19:45:28.372862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.513142111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:28.372909Z","caller":"traceutil/trace.go:171","msg":"trace[261618391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26515; }","duration":"1.519773426s","start":"2026-10-02T19:45:26.853114Z","end":"2026-10-02T19:45:28.372888Z","steps":["trace[261618391] 'agreement among raft nodes before linearized reading' (duration: 1.519577772s)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:28.372926Z","caller":"traceutil/trace.go:171","msg":"trace[200404348] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:26515; }","duration":"1.513254912s","start":"2026-10-02T19:45:26.859652Z","end":"2026-10-02T19:45:28.372907Z","steps":["trace[200404348] 'agreement among raft nodes before linearized reading' (duration: 1.51314697s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.372958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.853091Z","time spent":"1.519855187s","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-02T19:45:28.372969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.85963Z","time spent":"1.513327464s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-10-02T19:45:28.372934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786081656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:28.373061Z","caller":"traceutil/trace.go:171","msg":"trace[1793823485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26515; }","duration":"1.78627224s","start":"2026-10-02T19:45:26.586744Z","end":"2026-10-02T19:45:28.373016Z","steps":["trace[1793823485] 'agreement among raft nodes before linearized reading' (duration: 1.786052856s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.373134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.58671Z","time spent":"1.786414373s","remote":"127.0.0.1:47624","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-10-02T19:45:28.373343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.80631177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-10-02T19:45:28.373408Z","caller":"traceutil/trace.go:171","msg":"trace[27462379] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26515; }","duration":"1.806413712s","start":"2026-10-02T19:45:26.566977Z","end":"2026-10-02T19:45:28.373391Z","steps":["trace[27462379] 'agreement among raft nodes before linearized reading' (duration: 1.805337551s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.373448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:26.566923Z","time spent":"1.806513894s","remote":"127.0.0.1:47814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-02T19:45:28.373807Z","caller":"traceutil/trace.go:171","msg":"trace[1636147572] transaction","detail":"{read_only:false; response_revision:26516; number_of_response:1; }","duration":"422.311771ms","start":"2026-10-02T19:45:27.951466Z","end":"2026-10-02T19:45:28.373778Z","steps":["trace[1636147572] 'process raft request' (duration: 420.436675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.37396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.951436Z","time spent":"422.448264ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:28.732732Z","caller":"traceutil/trace.go:171","msg":"trace[2024483751] linearizableReadLoop","detail":"{readStateIndex:27402; appliedIndex:27401; }","duration":"360.437885ms","start":"2026-10-02T19:45:28.372265Z","end":"2026-10-02T19:45:28.732703Z","steps":["trace[2024483751] 'read index received' (duration: 310.802964ms)","trace[2024483751] 'applied index is now lower than readState.Index' (duration: 49.633101ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:28.733033Z","caller":"traceutil/trace.go:171","msg":"trace[639428452] transaction","detail":"{read_only:false; response_revision:26517; number_of_response:1; }","duration":"368.911627ms","start":"2026-10-02T19:45:28.364106Z","end":"2026-10-02T19:45:28.733017Z","steps":["trace[639428452] 'process raft request' (duration: 318.876819ms)","trace[639428452] 'compare' (duration: 49.533909ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:28.733159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.364079Z","time spent":"368.990128ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:28.733162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.954159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:45:28.733186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021964543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:28.733258Z","caller":"traceutil/trace.go:171","msg":"trace[597962432] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26517; }","duration":"678.108722ms","start":"2026-10-02T19:45:28.055118Z","end":"2026-10-02T19:45:28.733227Z","steps":["trace[597962432] 'agreement among raft nodes before linearized reading' (duration: 677.954049ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:28.733257Z","caller":"traceutil/trace.go:171","msg":"trace[590884605] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26517; }","duration":"1.022081166s","start":"2026-10-02T19:45:27.711157Z","end":"2026-10-02T19:45:28.733239Z","steps":["trace[590884605] 'agreement among raft nodes before linearized reading' (duration: 1.021922703s)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.733229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.713634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:45:28.733283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.963464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.213\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-10-02T19:45:28.733303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.71114Z","time spent":"1.022153487s","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-10-02T19:45:28.733299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.055096Z","time spent":"678.192514ms","remote":"127.0.0.1:42986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"info","ts":"2026-10-02T19:45:28.733342Z","caller":"traceutil/trace.go:171","msg":"trace[968866131] range","detail":"{range_begin:/registry/masterleases/162.253.55.213; range_end:; response_count:1; response_revision:26517; }","duration":"982.069086ms","start":"2026-10-02T19:45:27.75126Z","end":"2026-10-02T19:45:28.733329Z","steps":["trace[968866131] 'agreement among raft nodes before linearized reading' (duration: 981.773851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.733381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:27.751249Z","time spent":"982.124927ms","remote":"127.0.0.1:47662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/162.253.55.213\" "} {"level":"info","ts":"2026-10-02T19:45:28.733377Z","caller":"traceutil/trace.go:171","msg":"trace[1009047673] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:26517; }","duration":"377.925188ms","start":"2026-10-02T19:45:28.355401Z","end":"2026-10-02T19:45:28.733326Z","steps":["trace[1009047673] 'agreement among raft nodes before linearized reading' (duration: 377.657853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.733584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.355378Z","time spent":"378.0433ms","remote":"127.0.0.1:53300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"info","ts":"2026-10-02T19:45:28.86666Z","caller":"traceutil/trace.go:171","msg":"trace[581508127] transaction","detail":"{read_only:false; response_revision:26518; number_of_response:1; }","duration":"489.377153ms","start":"2026-10-02T19:45:28.377265Z","end":"2026-10-02T19:45:28.866642Z","steps":["trace[581508127] 'process raft request' (duration: 489.131278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.866789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.377243Z","time spent":"489.470584ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:28.86701Z","caller":"traceutil/trace.go:171","msg":"trace[746362323] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"488.449194ms","start":"2026-10-02T19:45:28.378536Z","end":"2026-10-02T19:45:28.866986Z","steps":["trace[746362323] 'process raft request' (duration: 488.056857ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:28.867063Z","caller":"traceutil/trace.go:171","msg":"trace[1765666439] linearizableReadLoop","detail":"{readStateIndex:27407; appliedIndex:27402; }","duration":"134.236417ms","start":"2026-10-02T19:45:28.732802Z","end":"2026-10-02T19:45:28.867038Z","steps":["trace[1765666439] 'read index received' (duration: 133.662476ms)","trace[1765666439] 'applied index is now lower than readState.Index' (duration: 572.221µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:28.867097Z","caller":"traceutil/trace.go:171","msg":"trace[1190277148] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"478.564057ms","start":"2026-10-02T19:45:28.388507Z","end":"2026-10-02T19:45:28.867071Z","steps":["trace[1190277148] 'process raft request' (duration: 478.444445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.867144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.378518Z","time spent":"488.551756ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:28.867187Z","caller":"traceutil/trace.go:171","msg":"trace[1595875992] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"477.902654ms","start":"2026-10-02T19:45:28.389269Z","end":"2026-10-02T19:45:28.867172Z","steps":["trace[1595875992] 'process raft request' (duration: 477.721751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.867228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.388482Z","time spent":"478.675689ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:28.867239Z","caller":"traceutil/trace.go:171","msg":"trace[1524635041] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"478.821092ms","start":"2026-10-02T19:45:28.388384Z","end":"2026-10-02T19:45:28.867205Z","steps":["trace[1524635041] 'process raft request' (duration: 478.446615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.8673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.389257Z","time spent":"477.963075ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:28.867333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.315359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"warn","ts":"2026-10-02T19:45:28.867409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.388371Z","time spent":"478.943924ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:28.867423Z","caller":"traceutil/trace.go:171","msg":"trace[1165484490] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26522; }","duration":"481.460782ms","start":"2026-10-02T19:45:28.385943Z","end":"2026-10-02T19:45:28.867404Z","steps":["trace[1165484490] 'agreement among raft nodes before linearized reading' (duration: 481.213507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.867364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.732363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:45:28.867464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.385932Z","time spent":"481.523394ms","remote":"127.0.0.1:47814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-02T19:45:28.867539Z","caller":"traceutil/trace.go:171","msg":"trace[1792847103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26522; }","duration":"475.895386ms","start":"2026-10-02T19:45:28.391594Z","end":"2026-10-02T19:45:28.867489Z","steps":["trace[1792847103] 'agreement among raft nodes before linearized reading' (duration: 475.56877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.867631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.391588Z","time spent":"476.030989ms","remote":"127.0.0.1:47618","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-10-02T19:45:28.868344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.700819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:28.868425Z","caller":"traceutil/trace.go:171","msg":"trace[291205395] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:26522; }","duration":"478.800492ms","start":"2026-10-02T19:45:28.389591Z","end":"2026-10-02T19:45:28.868391Z","steps":["trace[291205395] 'agreement among raft nodes before linearized reading' (duration: 477.534407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:28.868461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.389581Z","time spent":"478.871133ms","remote":"127.0.0.1:47734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"warn","ts":"2026-10-02T19:45:28.87414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.529801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:28.874197Z","caller":"traceutil/trace.go:171","msg":"trace[1315985303] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26522; }","duration":"135.648704ms","start":"2026-10-02T19:45:28.738534Z","end":"2026-10-02T19:45:28.874182Z","steps":["trace[1315985303] 'agreement among raft nodes before linearized reading' (duration: 135.537691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:29.374323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898203674602074212,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-02T19:45:29.418301Z","caller":"traceutil/trace.go:171","msg":"trace[739033227] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"547.935652ms","start":"2026-10-02T19:45:28.870336Z","end":"2026-10-02T19:45:29.418272Z","steps":["trace[739033227] 'process raft request' (duration: 547.550846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:29.418464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.870313Z","time spent":"548.076785ms","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1633,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T19:45:29.45879Z","caller":"traceutil/trace.go:171","msg":"trace[1789781213] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"583.959645ms","start":"2026-10-02T19:45:28.874814Z","end":"2026-10-02T19:45:29.458774Z","steps":["trace[1789781213] 'process raft request' (duration: 583.68131ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:29.458879Z","caller":"traceutil/trace.go:171","msg":"trace[25686681] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"510.783358ms","start":"2026-10-02T19:45:28.94805Z","end":"2026-10-02T19:45:29.458834Z","steps":["trace[25686681] 'process raft request' (duration: 510.605235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:29.458937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.874801Z","time spent":"584.051018ms","remote":"127.0.0.1:47662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:29.458993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.862717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"info","ts":"2026-10-02T19:45:29.459041Z","caller":"traceutil/trace.go:171","msg":"trace[483770122] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26525; }","duration":"589.933369ms","start":"2026-10-02T19:45:28.869094Z","end":"2026-10-02T19:45:29.459027Z","steps":["trace[483770122] 'agreement among raft nodes before linearized reading' (duration: 589.762876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:29.459043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.948005Z","time spent":"510.948341ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:29.459072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.869077Z","time spent":"589.98755ms","remote":"127.0.0.1:47812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19049,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-02T19:45:29.459158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.103692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:29.459233Z","caller":"traceutil/trace.go:171","msg":"trace[1045251006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26525; }","duration":"590.224235ms","start":"2026-10-02T19:45:28.868991Z","end":"2026-10-02T19:45:29.459216Z","steps":["trace[1045251006] 'agreement among raft nodes before linearized reading' (duration: 590.064301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:29.459274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.868977Z","time spent":"590.289206ms","remote":"127.0.0.1:47624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-02T19:45:29.458734Z","caller":"traceutil/trace.go:171","msg":"trace[657518194] linearizableReadLoop","detail":"{readStateIndex:27411; appliedIndex:27408; }","duration":"584.670749ms","start":"2026-10-02T19:45:28.874039Z","end":"2026-10-02T19:45:29.45871Z","steps":["trace[657518194] 'read index received' (duration: 543.912916ms)","trace[657518194] 'applied index is now lower than readState.Index' (duration: 40.756693ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:29.459598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.096323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:29.459656Z","caller":"traceutil/trace.go:171","msg":"trace[949042267] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26525; }","duration":"580.206885ms","start":"2026-10-02T19:45:28.879432Z","end":"2026-10-02T19:45:29.459639Z","steps":["trace[949042267] 'agreement among raft nodes before linearized reading' (duration: 580.062452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:29.459697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.87942Z","time spent":"580.265446ms","remote":"127.0.0.1:47888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-10-02T19:45:29.459724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.764968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:45:29.459761Z","caller":"traceutil/trace.go:171","msg":"trace[1249303566] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26525; }","duration":"442.830109ms","start":"2026-10-02T19:45:29.016919Z","end":"2026-10-02T19:45:29.459749Z","steps":["trace[1249303566] 'agreement among raft nodes before linearized reading' (duration: 442.761368ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:29.45979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:29.016904Z","time spent":"442.877499ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-02T19:45:29.4599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.995676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:45:29.459932Z","caller":"traceutil/trace.go:171","msg":"trace[1653231997] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:26525; }","duration":"576.046366ms","start":"2026-10-02T19:45:28.883873Z","end":"2026-10-02T19:45:29.45992Z","steps":["trace[1653231997] 'agreement among raft nodes before linearized reading' (duration: 575.989135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:29.459959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:28.883858Z","time spent":"576.093488ms","remote":"127.0.0.1:57576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"info","ts":"2026-10-02T19:45:29.72054Z","caller":"traceutil/trace.go:171","msg":"trace[1816086099] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"251.44349ms","start":"2026-10-02T19:45:29.46907Z","end":"2026-10-02T19:45:29.720514Z","steps":["trace[1816086099] 'process raft request' (duration: 250.300138ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:29.721032Z","caller":"traceutil/trace.go:171","msg":"trace[2143235553] linearizableReadLoop","detail":"{readStateIndex:27412; appliedIndex:27411; }","duration":"197.405854ms","start":"2026-10-02T19:45:29.523607Z","end":"2026-10-02T19:45:29.721013Z","steps":["trace[2143235553] 'read index received' (duration: 195.760303ms)","trace[2143235553] 'applied index is now lower than readState.Index' (duration: 1.643781ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:29.721189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.560976ms","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-02T19:45:29.721245Z","caller":"traceutil/trace.go:171","msg":"trace[141430726] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26530; }","duration":"197.686769ms","start":"2026-10-02T19:45:29.523544Z","end":"2026-10-02T19:45:29.72123Z","steps":["trace[141430726] 'agreement among raft nodes before linearized reading' (duration: 197.539736ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:33.612946Z","caller":"traceutil/trace.go:171","msg":"trace[405084171] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"142.032533ms","start":"2026-10-02T19:45:33.470885Z","end":"2026-10-02T19:45:33.612918Z","steps":["trace[405084171] 'process raft request' (duration: 141.806969ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:38.540051Z","caller":"traceutil/trace.go:171","msg":"trace[372436751] transaction","detail":"{read_only:false; response_revision:26663; number_of_response:1; }","duration":"110.76892ms","start":"2026-10-02T19:45:38.429254Z","end":"2026-10-02T19:45:38.540023Z","steps":["trace[372436751] 'process raft request' (duration: 109.759582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:39.48582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.480315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:39.485942Z","caller":"traceutil/trace.go:171","msg":"trace[2140300344] transaction","detail":"{read_only:false; response_revision:26671; number_of_response:1; }","duration":"246.185608ms","start":"2026-10-02T19:45:39.239733Z","end":"2026-10-02T19:45:39.485918Z","steps":["trace[2140300344] 'process raft request' (duration: 106.502649ms)","trace[2140300344] 'compare' (duration: 139.023667ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:39.715331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.374343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:45:39.715463Z","caller":"traceutil/trace.go:171","msg":"trace[1973012741] transaction","detail":"{read_only:false; response_revision:26672; number_of_response:1; }","duration":"475.407246ms","start":"2026-10-02T19:45:39.240041Z","end":"2026-10-02T19:45:39.715448Z","steps":["trace[1973012741] 'process raft request' (duration: 345.85655ms)","trace[1973012741] 'compare' (duration: 129.275121ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:45:39.715543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:39.240027Z","time spent":"475.490847ms","remote":"127.0.0.1:47888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:39.715801Z","caller":"traceutil/trace.go:171","msg":"trace[1634945297] linearizableReadLoop","detail":"{readStateIndex:27563; appliedIndex:27559; }","duration":"454.070102ms","start":"2026-10-02T19:45:39.261716Z","end":"2026-10-02T19:45:39.715786Z","steps":["trace[1634945297] 'read index received' (duration: 84.606695ms)","trace[1634945297] 'applied index is now lower than readState.Index' (duration: 369.461967ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:39.715806Z","caller":"traceutil/trace.go:171","msg":"trace[332152847] transaction","detail":"{read_only:false; response_revision:26673; number_of_response:1; }","duration":"474.693892ms","start":"2026-10-02T19:45:39.241073Z","end":"2026-10-02T19:45:39.715767Z","steps":["trace[332152847] 'process raft request' (duration: 474.333695ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:39.715833Z","caller":"traceutil/trace.go:171","msg":"trace[1109344623] transaction","detail":"{read_only:false; response_revision:26674; number_of_response:1; }","duration":"474.748543ms","start":"2026-10-02T19:45:39.241059Z","end":"2026-10-02T19:45:39.715808Z","steps":["trace[1109344623] 'process raft request' (duration: 474.540579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:39.715969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:39.241046Z","time spent":"474.846895ms","remote":"127.0.0.1:47804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T19:45:39.715983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.268695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-10-02T19:45:39.715976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:39.241063Z","time spent":"474.824985ms","remote":"127.0.0.1:47888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:45:39.716032Z","caller":"traceutil/trace.go:171","msg":"trace[1227983662] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26674; }","duration":"454.328197ms","start":"2026-10-02T19:45:39.261676Z","end":"2026-10-02T19:45:39.716004Z","steps":["trace[1227983662] 'agreement among raft nodes before linearized reading' (duration: 454.196424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:39.716063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:39.261664Z","time spent":"454.390258ms","remote":"127.0.0.1:47800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1882,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-10-02T19:45:39.784738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.391546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:39.784776Z","caller":"traceutil/trace.go:171","msg":"trace[98860049] transaction","detail":"{read_only:false; response_revision:26675; number_of_response:1; }","duration":"141.970493ms","start":"2026-10-02T19:45:39.642771Z","end":"2026-10-02T19:45:39.784742Z","steps":["trace[98860049] 'process raft request' (duration: 141.697728ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:39.784815Z","caller":"traceutil/trace.go:171","msg":"trace[2106682563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26675; }","duration":"206.506367ms","start":"2026-10-02T19:45:39.578291Z","end":"2026-10-02T19:45:39.784798Z","steps":["trace[2106682563] 'agreement among raft nodes before linearized reading' (duration: 206.355544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:39.784817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.779798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-02T19:45:39.78487Z","caller":"traceutil/trace.go:171","msg":"trace[1521599286] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26675; }","duration":"142.867129ms","start":"2026-10-02T19:45:39.641989Z","end":"2026-10-02T19:45:39.784856Z","steps":["trace[1521599286] 'agreement among raft nodes before linearized reading' (duration: 142.649785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:39.784896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.866747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:39.784984Z","caller":"traceutil/trace.go:171","msg":"trace[762759493] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26675; }","duration":"310.009979ms","start":"2026-10-02T19:45:39.47495Z","end":"2026-10-02T19:45:39.78496Z","steps":["trace[762759493] 'agreement among raft nodes before linearized reading' (duration: 309.850067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:39.785033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:39.474929Z","time spent":"310.094111ms","remote":"127.0.0.1:32790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-10-02T19:45:39.785112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.999426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-10-02T19:45:39.785262Z","caller":"traceutil/trace.go:171","msg":"trace[983624609] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26675; }","duration":"305.363081ms","start":"2026-10-02T19:45:39.479802Z","end":"2026-10-02T19:45:39.785165Z","steps":["trace[983624609] 'agreement among raft nodes before linearized reading' (duration: 304.874523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:39.785375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:45:39.479792Z","time spent":"305.509284ms","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3331,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-10-02T19:45:39.935781Z","caller":"traceutil/trace.go:171","msg":"trace[1677169830] linearizableReadLoop","detail":"{readStateIndex:27568; appliedIndex:27564; }","duration":"151.143966ms","start":"2026-10-02T19:45:39.78462Z","end":"2026-10-02T19:45:39.935764Z","steps":["trace[1677169830] 'read index received' (duration: 150.960093ms)","trace[1677169830] 'applied index is now lower than readState.Index' (duration: 183.073µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:45:39.935974Z","caller":"traceutil/trace.go:171","msg":"trace[287881720] transaction","detail":"{read_only:false; response_revision:26676; number_of_response:1; }","duration":"162.467541ms","start":"2026-10-02T19:45:39.773499Z","end":"2026-10-02T19:45:39.935966Z","steps":["trace[287881720] 'process raft request' (duration: 162.087384ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:39.936111Z","caller":"traceutil/trace.go:171","msg":"trace[1746739855] transaction","detail":"{read_only:false; response_revision:26678; number_of_response:1; }","duration":"160.938732ms","start":"2026-10-02T19:45:39.775152Z","end":"2026-10-02T19:45:39.936091Z","steps":["trace[1746739855] 'process raft request' (duration: 160.565615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:39.936167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.425922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T19:45:39.936201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.902473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2725d85a-1047-428c-8ffd-208b5f937e13\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-02T19:45:39.936237Z","caller":"traceutil/trace.go:171","msg":"trace[1061440752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26679; }","duration":"150.536705ms","start":"2026-10-02T19:45:39.785685Z","end":"2026-10-02T19:45:39.936221Z","steps":["trace[1061440752] 'agreement among raft nodes before linearized reading' (duration: 150.411764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:39.936216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.083154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:45:39.936316Z","caller":"traceutil/trace.go:171","msg":"trace[1643479118] transaction","detail":"{read_only:false; response_revision:26679; number_of_response:1; }","duration":"160.130567ms","start":"2026-10-02T19:45:39.776176Z","end":"2026-10-02T19:45:39.936306Z","steps":["trace[1643479118] 'process raft request' (duration: 159.560547ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:39.936343Z","caller":"traceutil/trace.go:171","msg":"trace[743169141] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:26679; }","duration":"142.242838ms","start":"2026-10-02T19:45:39.794061Z","end":"2026-10-02T19:45:39.936304Z","steps":["trace[743169141] 'agreement among raft nodes before linearized reading' (duration: 142.055074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:45:39.936407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.326014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:45:39.936438Z","caller":"traceutil/trace.go:171","msg":"trace[759085419] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26679; }","duration":"146.386235ms","start":"2026-10-02T19:45:39.790042Z","end":"2026-10-02T19:45:39.936429Z","steps":["trace[759085419] 'agreement among raft nodes before linearized reading' (duration: 146.322534ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:39.936281Z","caller":"traceutil/trace.go:171","msg":"trace[313207904] transaction","detail":"{read_only:false; response_revision:26677; number_of_response:1; }","duration":"161.202847ms","start":"2026-10-02T19:45:39.77507Z","end":"2026-10-02T19:45:39.936273Z","steps":["trace[313207904] 'process raft request' (duration: 160.623957ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:39.936271Z","caller":"traceutil/trace.go:171","msg":"trace[1564820941] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2725d85a-1047-428c-8ffd-208b5f937e13; range_end:; response_count:1; response_revision:26679; }","duration":"217.018196ms","start":"2026-10-02T19:45:39.719236Z","end":"2026-10-02T19:45:39.936255Z","steps":["trace[1564820941] 'agreement among raft nodes before linearized reading' (duration: 216.819013ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:41.924634Z","caller":"traceutil/trace.go:171","msg":"trace[519823042] transaction","detail":"{read_only:false; response_revision:26697; number_of_response:1; }","duration":"134.370609ms","start":"2026-10-02T19:45:41.790239Z","end":"2026-10-02T19:45:41.92461Z","steps":["trace[519823042] 'process raft request' (duration: 134.110364ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:44.084878Z","caller":"traceutil/trace.go:171","msg":"trace[773367531] transaction","detail":"{read_only:false; response_revision:26713; number_of_response:1; }","duration":"154.50236ms","start":"2026-10-02T19:45:43.93035Z","end":"2026-10-02T19:45:44.084852Z","steps":["trace[773367531] 'process raft request' (duration: 154.346297ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:44.086413Z","caller":"traceutil/trace.go:171","msg":"trace[1942625498] transaction","detail":"{read_only:false; response_revision:26714; number_of_response:1; }","duration":"136.042529ms","start":"2026-10-02T19:45:43.950339Z","end":"2026-10-02T19:45:44.086382Z","steps":["trace[1942625498] 'process raft request' (duration: 135.621991ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:44.086443Z","caller":"traceutil/trace.go:171","msg":"trace[2051971213] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"135.858356ms","start":"2026-10-02T19:45:43.950495Z","end":"2026-10-02T19:45:44.086353Z","steps":["trace[2051971213] 'process raft request' (duration: 135.727633ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:44.086609Z","caller":"traceutil/trace.go:171","msg":"trace[1681662009] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"135.971028ms","start":"2026-10-02T19:45:43.950576Z","end":"2026-10-02T19:45:44.086547Z","steps":["trace[1681662009] 'process raft request' (duration: 135.717494ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:45:44.086759Z","caller":"traceutil/trace.go:171","msg":"trace[279325304] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"135.249795ms","start":"2026-10-02T19:45:43.951481Z","end":"2026-10-02T19:45:44.08673Z","steps":["trace[279325304] 'process raft request' (duration: 134.936439ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:02.259274Z","caller":"traceutil/trace.go:171","msg":"trace[1530248637] transaction","detail":"{read_only:false; response_revision:26862; number_of_response:1; }","duration":"109.767841ms","start":"2026-10-02T19:46:02.149473Z","end":"2026-10-02T19:46:02.259241Z","steps":["trace[1530248637] 'process raft request' (duration: 109.518246ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:02.259305Z","caller":"traceutil/trace.go:171","msg":"trace[977570085] transaction","detail":"{read_only:false; response_revision:26863; number_of_response:1; }","duration":"106.977039ms","start":"2026-10-02T19:46:02.152294Z","end":"2026-10-02T19:46:02.259271Z","steps":["trace[977570085] 'process raft request' (duration: 106.807756ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:02.60665Z","caller":"traceutil/trace.go:171","msg":"trace[1246264241] transaction","detail":"{read_only:false; response_revision:26866; number_of_response:1; }","duration":"105.141035ms","start":"2026-10-02T19:46:02.501484Z","end":"2026-10-02T19:46:02.606625Z","steps":["trace[1246264241] 'process raft request' (duration: 104.873729ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:02.917807Z","caller":"traceutil/trace.go:171","msg":"trace[2142099613] transaction","detail":"{read_only:false; response_revision:26869; number_of_response:1; }","duration":"109.487455ms","start":"2026-10-02T19:46:02.808286Z","end":"2026-10-02T19:46:02.917773Z","steps":["trace[2142099613] 'process raft request' (duration: 89.603799ms)","trace[2142099613] 'compare' (duration: 19.477709ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:46:02.9665Z","caller":"traceutil/trace.go:171","msg":"trace[374747123] transaction","detail":"{read_only:false; response_revision:26870; number_of_response:1; }","duration":"105.039411ms","start":"2026-10-02T19:46:02.861442Z","end":"2026-10-02T19:46:02.966481Z","steps":["trace[374747123] 'process raft request' (duration: 104.929429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:46:04.437867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.715939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:46:04.438086Z","caller":"traceutil/trace.go:171","msg":"trace[1241190697] transaction","detail":"{read_only:false; response_revision:26877; number_of_response:1; }","duration":"173.441279ms","start":"2026-10-02T19:46:04.264614Z","end":"2026-10-02T19:46:04.438055Z","steps":["trace[1241190697] 'process raft request' (duration: 61.382954ms)","trace[1241190697] 'compare' (duration: 111.404344ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:46:04.438374Z","caller":"traceutil/trace.go:171","msg":"trace[2093432839] transaction","detail":"{read_only:false; response_revision:26879; number_of_response:1; }","duration":"172.273276ms","start":"2026-10-02T19:46:04.26609Z","end":"2026-10-02T19:46:04.438363Z","steps":["trace[2093432839] 'process raft request' (duration: 172.032862ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:04.438494Z","caller":"traceutil/trace.go:171","msg":"trace[1900118281] transaction","detail":"{read_only:false; response_revision:26880; number_of_response:1; }","duration":"170.927971ms","start":"2026-10-02T19:46:04.267541Z","end":"2026-10-02T19:46:04.438469Z","steps":["trace[1900118281] 'process raft request' (duration: 170.634656ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:04.438622Z","caller":"traceutil/trace.go:171","msg":"trace[1349792377] transaction","detail":"{read_only:false; response_revision:26878; number_of_response:1; }","duration":"173.820797ms","start":"2026-10-02T19:46:04.264764Z","end":"2026-10-02T19:46:04.438585Z","steps":["trace[1349792377] 'process raft request' (duration: 173.234485ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:04.848241Z","caller":"traceutil/trace.go:171","msg":"trace[869055623] transaction","detail":"{read_only:false; response_revision:26882; number_of_response:1; }","duration":"125.734044ms","start":"2026-10-02T19:46:04.722472Z","end":"2026-10-02T19:46:04.848206Z","steps":["trace[869055623] 'process raft request' (duration: 96.690333ms)","trace[869055623] 'compare' (duration: 28.527181ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:46:16.617168Z","caller":"traceutil/trace.go:171","msg":"trace[1951167397] transaction","detail":"{read_only:false; response_revision:26969; number_of_response:1; }","duration":"135.150934ms","start":"2026-10-02T19:46:16.481991Z","end":"2026-10-02T19:46:16.617142Z","steps":["trace[1951167397] 'process raft request' (duration: 135.115563ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:16.617259Z","caller":"traceutil/trace.go:171","msg":"trace[1990398927] transaction","detail":"{read_only:false; response_revision:26968; number_of_response:1; }","duration":"135.389208ms","start":"2026-10-02T19:46:16.48184Z","end":"2026-10-02T19:46:16.617229Z","steps":["trace[1990398927] 'process raft request' (duration: 135.171914ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:16.617255Z","caller":"traceutil/trace.go:171","msg":"trace[2021416774] transaction","detail":"{read_only:false; response_revision:26967; number_of_response:1; }","duration":"137.165271ms","start":"2026-10-02T19:46:16.48006Z","end":"2026-10-02T19:46:16.617225Z","steps":["trace[2021416774] 'process raft request' (duration: 51.283143ms)","trace[2021416774] 'compare' (duration: 85.346358ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:46:17.171904Z","caller":"traceutil/trace.go:171","msg":"trace[1997437743] transaction","detail":"{read_only:false; response_revision:26972; number_of_response:1; }","duration":"113.909911ms","start":"2026-10-02T19:46:17.057972Z","end":"2026-10-02T19:46:17.171882Z","steps":["trace[1997437743] 'process raft request' (duration: 113.760538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:46:26.817564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.834764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:46:26.817705Z","caller":"traceutil/trace.go:171","msg":"trace[175774809] transaction","detail":"{read_only:false; response_revision:27041; number_of_response:1; }","duration":"151.31934ms","start":"2026-10-02T19:46:26.666373Z","end":"2026-10-02T19:46:26.817692Z","steps":["trace[175774809] 'process raft request' (duration: 151.294259ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:26.81775Z","caller":"traceutil/trace.go:171","msg":"trace[76906935] transaction","detail":"{read_only:false; response_revision:27039; number_of_response:1; }","duration":"155.06818ms","start":"2026-10-02T19:46:26.662662Z","end":"2026-10-02T19:46:26.81773Z","steps":["trace[76906935] 'compare' (duration: 146.755853ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:26.817774Z","caller":"traceutil/trace.go:171","msg":"trace[1297090614] transaction","detail":"{read_only:false; response_revision:27040; number_of_response:1; }","duration":"154.077411ms","start":"2026-10-02T19:46:26.663661Z","end":"2026-10-02T19:46:26.817739Z","steps":["trace[1297090614] 'process raft request' (duration: 153.97648ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:27.114593Z","caller":"traceutil/trace.go:171","msg":"trace[610961363] transaction","detail":"{read_only:false; response_revision:27042; number_of_response:1; }","duration":"374.543283ms","start":"2026-10-02T19:46:26.739994Z","end":"2026-10-02T19:46:27.114538Z","steps":["trace[610961363] 'process raft request' (duration: 301.195572ms)","trace[610961363] 'compare' (duration: 73.127947ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:46:27.114798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:46:26.739978Z","time spent":"374.715356ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:46:27.117124Z","caller":"traceutil/trace.go:171","msg":"trace[786490651] transaction","detail":"{read_only:false; response_revision:27043; number_of_response:1; }","duration":"165.516739ms","start":"2026-10-02T19:46:26.951582Z","end":"2026-10-02T19:46:27.117098Z","steps":["trace[786490651] 'process raft request' (duration: 165.319255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:46:27.413936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.507878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:46:27.414299Z","caller":"traceutil/trace.go:171","msg":"trace[272148085] transaction","detail":"{read_only:false; response_revision:27044; number_of_response:1; }","duration":"191.41996ms","start":"2026-10-02T19:46:27.222859Z","end":"2026-10-02T19:46:27.414279Z","steps":["trace[272148085] 'process raft request' (duration: 53.471603ms)","trace[272148085] 'compare' (duration: 137.350195ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:46:27.70853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.403699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:46:27.708661Z","caller":"traceutil/trace.go:171","msg":"trace[33055920] linearizableReadLoop","detail":"{readStateIndex:27943; appliedIndex:27942; }","duration":"254.65157ms","start":"2026-10-02T19:46:27.453993Z","end":"2026-10-02T19:46:27.708645Z","steps":["trace[33055920] 'read index received' (duration: 118.930516ms)","trace[33055920] 'applied index is now lower than readState.Index' (duration: 135.719364ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:46:27.70876Z","caller":"traceutil/trace.go:171","msg":"trace[1602494164] transaction","detail":"{read_only:false; response_revision:27045; number_of_response:1; }","duration":"257.449193ms","start":"2026-10-02T19:46:27.451295Z","end":"2026-10-02T19:46:27.708744Z","steps":["trace[1602494164] 'process raft request' (duration: 121.741239ms)","trace[1602494164] 'compare' (duration: 135.178003ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:46:27.708985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.131309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-02T19:46:27.709004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.263435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-02T19:46:27.709064Z","caller":"traceutil/trace.go:171","msg":"trace[1973247309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27045; }","duration":"226.23522ms","start":"2026-10-02T19:46:27.482809Z","end":"2026-10-02T19:46:27.709044Z","steps":["trace[1973247309] 'agreement among raft nodes before linearized reading' (duration: 226.037487ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:27.709078Z","caller":"traceutil/trace.go:171","msg":"trace[135287676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27045; }","duration":"231.371308ms","start":"2026-10-02T19:46:27.477688Z","end":"2026-10-02T19:46:27.709059Z","steps":["trace[135287676] 'agreement among raft nodes before linearized reading' (duration: 231.155034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:46:27.709188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.925601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-02T19:46:27.709284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.973062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-02T19:46:27.709287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.285072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-13720dcc-dec8-4644-a7b1-45ebd3d7a9f6\" ","response":"range_response_count:1 size:1319"} {"level":"warn","ts":"2026-10-02T19:46:27.709306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.300523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:46:27.709305Z","caller":"traceutil/trace.go:171","msg":"trace[1122797709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27045; }","duration":"229.064464ms","start":"2026-10-02T19:46:27.480206Z","end":"2026-10-02T19:46:27.70927Z","steps":["trace[1122797709] 'agreement among raft nodes before linearized reading' (duration: 228.663316ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:27.709331Z","caller":"traceutil/trace.go:171","msg":"trace[522264605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27045; }","duration":"230.018893ms","start":"2026-10-02T19:46:27.479303Z","end":"2026-10-02T19:46:27.709322Z","steps":["trace[522264605] 'agreement among raft nodes before linearized reading' (duration: 229.919431ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:27.709341Z","caller":"traceutil/trace.go:171","msg":"trace[452281588] range","detail":"{range_begin:/registry/persistentvolumes/pvc-13720dcc-dec8-4644-a7b1-45ebd3d7a9f6; range_end:; response_count:1; response_revision:27045; }","duration":"255.351863ms","start":"2026-10-02T19:46:27.453973Z","end":"2026-10-02T19:46:27.709324Z","steps":["trace[452281588] 'agreement among raft nodes before linearized reading' (duration: 255.162319ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:27.709342Z","caller":"traceutil/trace.go:171","msg":"trace[1207534786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27045; }","duration":"128.371254ms","start":"2026-10-02T19:46:27.580962Z","end":"2026-10-02T19:46:27.709333Z","steps":["trace[1207534786] 'agreement among raft nodes before linearized reading' (duration: 128.308803ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:27.849422Z","caller":"traceutil/trace.go:171","msg":"trace[907366845] transaction","detail":"{read_only:false; response_revision:27049; number_of_response:1; }","duration":"132.786898ms","start":"2026-10-02T19:46:27.716605Z","end":"2026-10-02T19:46:27.849392Z","steps":["trace[907366845] 'process raft request' (duration: 132.684856ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:27.849442Z","caller":"traceutil/trace.go:171","msg":"trace[1050788021] transaction","detail":"{read_only:false; response_revision:27047; number_of_response:1; }","duration":"135.223095ms","start":"2026-10-02T19:46:27.714166Z","end":"2026-10-02T19:46:27.849389Z","steps":["trace[1050788021] 'process raft request' (duration: 134.863948ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:27.849459Z","caller":"traceutil/trace.go:171","msg":"trace[1886051096] transaction","detail":"{read_only:false; response_revision:27048; number_of_response:1; }","duration":"135.231505ms","start":"2026-10-02T19:46:27.714196Z","end":"2026-10-02T19:46:27.849428Z","steps":["trace[1886051096] 'process raft request' (duration: 135.038871ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:27.849767Z","caller":"traceutil/trace.go:171","msg":"trace[626696353] transaction","detail":"{read_only:false; response_revision:27050; number_of_response:1; }","duration":"117.452297ms","start":"2026-10-02T19:46:27.732301Z","end":"2026-10-02T19:46:27.849754Z","steps":["trace[626696353] 'process raft request' (duration: 117.046499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:46:28.137768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.969639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:46:28.13787Z","caller":"traceutil/trace.go:171","msg":"trace[143240525] linearizableReadLoop","detail":"{readStateIndex:27951; appliedIndex:27950; }","duration":"175.490079ms","start":"2026-10-02T19:46:27.962362Z","end":"2026-10-02T19:46:28.137853Z","steps":["trace[143240525] 'read index received' (duration: 68.294006ms)","trace[143240525] 'applied index is now lower than readState.Index' (duration: 107.194613ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:46:28.137942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.58346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:46:28.137974Z","caller":"traceutil/trace.go:171","msg":"trace[296567585] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:27052; }","duration":"175.655262ms","start":"2026-10-02T19:46:27.962308Z","end":"2026-10-02T19:46:28.137963Z","steps":["trace[296567585] 'agreement among raft nodes before linearized reading' (duration: 175.594401ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:28.138107Z","caller":"traceutil/trace.go:171","msg":"trace[1491692306] transaction","detail":"{read_only:false; response_revision:27052; number_of_response:1; }","duration":"189.842111ms","start":"2026-10-02T19:46:27.94821Z","end":"2026-10-02T19:46:28.138052Z","steps":["trace[1491692306] 'process raft request' (duration: 82.488054ms)","trace[1491692306] 'compare' (duration: 106.802686ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:46:28.775631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.582661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T19:46:28.775845Z","caller":"traceutil/trace.go:171","msg":"trace[151263386] transaction","detail":"{read_only:false; response_revision:27055; number_of_response:1; }","duration":"250.92659ms","start":"2026-10-02T19:46:28.524899Z","end":"2026-10-02T19:46:28.775826Z","steps":["trace[151263386] 'process raft request' (duration: 250.845648ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:28.775863Z","caller":"traceutil/trace.go:171","msg":"trace[869020565] linearizableReadLoop","detail":"{readStateIndex:27953; appliedIndex:27952; }","duration":"286.896702ms","start":"2026-10-02T19:46:28.488933Z","end":"2026-10-02T19:46:28.77583Z","steps":["trace[869020565] 'read index received' (duration: 161.000904ms)","trace[869020565] 'applied index is now lower than readState.Index' (duration: 125.893768ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:46:28.776057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.104575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:46:28.776094Z","caller":"traceutil/trace.go:171","msg":"trace[328835345] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:27055; }","duration":"287.196278ms","start":"2026-10-02T19:46:28.488883Z","end":"2026-10-02T19:46:28.776079Z","steps":["trace[328835345] 'agreement among raft nodes before linearized reading' (duration: 287.044615ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:28.776307Z","caller":"traceutil/trace.go:171","msg":"trace[221216300] transaction","detail":"{read_only:false; response_revision:27054; number_of_response:1; }","duration":"369.11357ms","start":"2026-10-02T19:46:28.40718Z","end":"2026-10-02T19:46:28.776294Z","steps":["trace[221216300] 'process raft request' (duration: 242.744803ms)","trace[221216300] 'compare' (duration: 125.298926ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T19:46:28.776402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T19:46:28.407167Z","time spent":"369.173852ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T19:46:55.237737Z","caller":"traceutil/trace.go:171","msg":"trace[114447907] transaction","detail":"{read_only:false; response_revision:27401; number_of_response:1; }","duration":"206.63426ms","start":"2026-10-02T19:46:55.031075Z","end":"2026-10-02T19:46:55.237709Z","steps":["trace[114447907] 'process raft request' (duration: 206.455597ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:55.314692Z","caller":"traceutil/trace.go:171","msg":"trace[649667110] transaction","detail":"{read_only:false; response_revision:27402; number_of_response:1; }","duration":"279.780616ms","start":"2026-10-02T19:46:55.034871Z","end":"2026-10-02T19:46:55.314651Z","steps":["trace[649667110] 'process raft request' (duration: 279.45047ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:55.315378Z","caller":"traceutil/trace.go:171","msg":"trace[2039660740] linearizableReadLoop","detail":"{readStateIndex:28309; appliedIndex:28306; }","duration":"112.925043ms","start":"2026-10-02T19:46:55.202431Z","end":"2026-10-02T19:46:55.315356Z","steps":["trace[2039660740] 'read index received' (duration: 35.097617ms)","trace[2039660740] 'applied index is now lower than readState.Index' (duration: 77.825526ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T19:46:55.3155Z","caller":"traceutil/trace.go:171","msg":"trace[1269406669] transaction","detail":"{read_only:false; response_revision:27403; number_of_response:1; }","duration":"277.670366ms","start":"2026-10-02T19:46:55.037785Z","end":"2026-10-02T19:46:55.315455Z","steps":["trace[1269406669] 'process raft request' (duration: 276.788349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:46:55.315586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.110526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T19:46:55.315655Z","caller":"traceutil/trace.go:171","msg":"trace[1507393546] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:27403; }","duration":"113.270659ms","start":"2026-10-02T19:46:55.202368Z","end":"2026-10-02T19:46:55.315639Z","steps":["trace[1507393546] 'agreement among raft nodes before linearized reading' (duration: 113.066315ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:55.439847Z","caller":"traceutil/trace.go:171","msg":"trace[1787732095] transaction","detail":"{read_only:false; response_revision:27404; number_of_response:1; }","duration":"191.190345ms","start":"2026-10-02T19:46:55.24863Z","end":"2026-10-02T19:46:55.439821Z","steps":["trace[1787732095] 'process raft request' (duration: 190.944751ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:55.440221Z","caller":"traceutil/trace.go:171","msg":"trace[717689843] transaction","detail":"{read_only:false; response_revision:27405; number_of_response:1; }","duration":"187.912664ms","start":"2026-10-02T19:46:55.25227Z","end":"2026-10-02T19:46:55.440183Z","steps":["trace[717689843] 'process raft request' (duration: 187.506246ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:55.440263Z","caller":"traceutil/trace.go:171","msg":"trace[748687694] transaction","detail":"{read_only:false; response_revision:27406; number_of_response:1; }","duration":"174.238624ms","start":"2026-10-02T19:46:55.265997Z","end":"2026-10-02T19:46:55.440236Z","steps":["trace[748687694] 'process raft request' (duration: 174.080271ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:46:55.648215Z","caller":"traceutil/trace.go:171","msg":"trace[1231234884] transaction","detail":"{read_only:false; response_revision:27407; number_of_response:1; }","duration":"122.445922ms","start":"2026-10-02T19:46:55.525724Z","end":"2026-10-02T19:46:55.64817Z","steps":["trace[1231234884] 'process raft request' (duration: 118.750042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T19:46:55.92964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.0581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T19:46:55.929732Z","caller":"traceutil/trace.go:171","msg":"trace[1697106142] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27407; }","duration":"115.226244ms","start":"2026-10-02T19:46:55.814481Z","end":"2026-10-02T19:46:55.929707Z","steps":["trace[1697106142] 'count revisions from in-memory index tree' (duration: 114.872897ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T19:47:04.278162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24556} {"level":"info","ts":"2026-10-02T19:47:04.438588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24556,"took":"155.719253ms","hash":2778985802,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":35889152,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-02T19:47:04.438668Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2778985802,"revision":24556,"compact-revision":21561} {"level":"info","ts":"2026-10-02T19:49:50.99138Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"43ce62d0a10d51da","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T19:49:50.996104Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-02T19:49:50.996216Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-02T19:52:04.285909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27503} {"level":"info","ts":"2026-10-02T19:52:04.422057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27503,"took":"131.998203ms","hash":1770277285,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":37474304,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-02T19:52:04.422142Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1770277285,"revision":27503,"compact-revision":24556} {"level":"info","ts":"2026-10-02T19:57:04.292865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30209} {"level":"info","ts":"2026-10-02T19:57:04.44901Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30209,"took":"151.972362ms","hash":3270999435,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":31059968,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T19:57:04.449078Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3270999435,"revision":30209,"compact-revision":27503} {"level":"info","ts":"2026-10-02T20:02:04.300557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32464} {"level":"info","ts":"2026-10-02T20:02:04.457134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32464,"took":"151.85134ms","hash":1986396656,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31342592,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T20:02:04.457229Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1986396656,"revision":32464,"compact-revision":30209} {"level":"info","ts":"2026-10-02T20:07:04.307713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34750} {"level":"info","ts":"2026-10-02T20:07:04.452923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34750,"took":"138.932375ms","hash":1575763504,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31473664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-02T20:07:04.452997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1575763504,"revision":34750,"compact-revision":32464} {"level":"info","ts":"2026-10-02T20:10:46.083782Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"43ce62d0a10d51da","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T20:10:46.08989Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-02T20:10:46.089986Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-02T20:12:04.315324Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37082} {"level":"info","ts":"2026-10-02T20:12:04.47915Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37082,"took":"159.148338ms","hash":2319903907,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":32337920,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-02T20:12:04.479241Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2319903907,"revision":37082,"compact-revision":34750} {"level":"info","ts":"2026-10-02T20:17:04.323072Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39354} {"level":"info","ts":"2026-10-02T20:17:04.46532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39354,"took":"137.584328ms","hash":2742371302,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":30408704,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-02T20:17:04.465391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2742371302,"revision":39354,"compact-revision":37082} {"level":"info","ts":"2026-10-02T20:22:04.330185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41586} {"level":"info","ts":"2026-10-02T20:22:04.457796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41586,"took":"123.648965ms","hash":3762652944,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":29057024,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-02T20:22:04.457871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3762652944,"revision":41586,"compact-revision":39354} {"level":"info","ts":"2026-10-02T20:27:04.338806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43815} {"level":"info","ts":"2026-10-02T20:27:04.468644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43815,"took":"125.909308ms","hash":3055654188,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":28835840,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-02T20:27:04.468737Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3055654188,"revision":43815,"compact-revision":41586}