{"level":"warn","ts":"2026-09-07T18:20:01.163032Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T18:20:01.16311Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.76: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.76:2380","--initial-cluster=instance=https://162.253.55.76:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.76:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.76: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-09-07T18:20:01.163168Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T18:20:01.163175Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.76:2380"]} {"level":"info","ts":"2026-09-07T18:20:01.163191Z","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-09-07T18:20:01.163835Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.76:2379"]} {"level":"info","ts":"2026-09-07T18:20:01.163931Z","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.76:2380"],"listen-peer-urls":["https://162.253.55.76:2380"],"advertise-client-urls":["https://162.253.55.76:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.76:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.76: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-09-07T18:20:01.167621Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.061646ms"} {"level":"info","ts":"2026-09-07T18:20:01.172087Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30ad0c2b6ecdd25e","cluster-id":"855d190e6ef0a61f"} {"level":"info","ts":"2026-09-07T18:20:01.172149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T18:20:01.172163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became follower at term 0"} {"level":"info","ts":"2026-09-07T18:20:01.17217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30ad0c2b6ecdd25e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T18:20:01.17218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became follower at term 1"} {"level":"info","ts":"2026-09-07T18:20:01.172209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e switched to configuration voters=(3507473065473593950)"} {"level":"warn","ts":"2026-09-07T18:20:01.177362Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T18:20:01.178826Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T18:20:01.180676Z","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-09-07T18:20:01.182722Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30ad0c2b6ecdd25e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T18:20:01.182896Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30ad0c2b6ecdd25e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T18:20:01.182986Z","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-09-07T18:20:01.183144Z","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-09-07T18:20:01.183161Z","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-09-07T18:20:01.185611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e switched to configuration voters=(3507473065473593950)"} {"level":"info","ts":"2026-09-07T18:20:01.185807Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"855d190e6ef0a61f","local-member-id":"30ad0c2b6ecdd25e","added-peer-id":"30ad0c2b6ecdd25e","added-peer-peer-urls":["https://162.253.55.76:2380"]} {"level":"info","ts":"2026-09-07T18:20:01.187431Z","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-09-07T18:20:01.187661Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.76:2380"} {"level":"info","ts":"2026-09-07T18:20:01.187717Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.76:2380"} {"level":"info","ts":"2026-09-07T18:20:01.187813Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30ad0c2b6ecdd25e","initial-advertise-peer-urls":["https://162.253.55.76:2380"],"listen-peer-urls":["https://162.253.55.76:2380"],"advertise-client-urls":["https://162.253.55.76:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.76:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T18:20:01.18788Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T18:20:01.575816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T18:20:01.575887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T18:20:01.575925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e received MsgPreVoteResp from 30ad0c2b6ecdd25e at term 1"} {"level":"info","ts":"2026-09-07T18:20:01.575953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became candidate at term 2"} {"level":"info","ts":"2026-09-07T18:20:01.575974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e received MsgVoteResp from 30ad0c2b6ecdd25e at term 2"} {"level":"info","ts":"2026-09-07T18:20:01.575999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became leader at term 2"} {"level":"info","ts":"2026-09-07T18:20:01.576021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30ad0c2b6ecdd25e elected leader 30ad0c2b6ecdd25e at term 2"} {"level":"info","ts":"2026-09-07T18:20:01.577533Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:20:01.579713Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30ad0c2b6ecdd25e","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.76:2379]}","request-path":"/0/members/30ad0c2b6ecdd25e/attributes","cluster-id":"855d190e6ef0a61f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T18:20:01.579826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:20:01.579933Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:20:01.581313Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.76:2379"} {"level":"info","ts":"2026-09-07T18:20:01.582405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T18:20:01.582594Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"855d190e6ef0a61f","local-member-id":"30ad0c2b6ecdd25e","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:20:01.582733Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:20:01.582769Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:20:01.587769Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T18:20:01.587787Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T18:20:21.573205Z","caller":"traceutil/trace.go:171","msg":"trace[1927538371] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"122.561094ms","start":"2026-09-07T18:20:21.45062Z","end":"2026-09-07T18:20:21.573181Z","steps":["trace[1927538371] 'process raft request' (duration: 122.416121ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:20:39.54283Z","caller":"traceutil/trace.go:171","msg":"trace[2059516507] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"110.508666ms","start":"2026-09-07T18:20:39.432282Z","end":"2026-09-07T18:20:39.542791Z","steps":["trace[2059516507] 'process raft request' (duration: 83.577885ms)","trace[2059516507] 'compare' (duration: 26.39064ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:20:46.559314Z","caller":"traceutil/trace.go:171","msg":"trace[1246225114] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"308.304689ms","start":"2026-09-07T18:20:46.250992Z","end":"2026-09-07T18:20:46.559296Z","steps":["trace[1246225114] 'process raft request' (duration: 307.85514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:20:46.559701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:20:46.250961Z","time spent":"308.415651ms","remote":"127.0.0.1:48158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:20:47.015105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.744854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:20:47.0153Z","caller":"traceutil/trace.go:171","msg":"trace[82074345] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"361.60028ms","start":"2026-09-07T18:20:46.653677Z","end":"2026-09-07T18:20:47.015277Z","steps":["trace[82074345] 'read index received' (duration: 217.322185ms)","trace[82074345] 'applied index is now lower than readState.Index' (duration: 144.276215ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:20:47.015359Z","caller":"traceutil/trace.go:171","msg":"trace[57243889] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"405.290389ms","start":"2026-09-07T18:20:46.61002Z","end":"2026-09-07T18:20:47.015311Z","steps":["trace[57243889] 'process raft request' (duration: 261.046805ms)","trace[57243889] 'compare' (duration: 143.521109ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:20:47.015421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.748214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:20:47.015462Z","caller":"traceutil/trace.go:171","msg":"trace[1305767582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"361.848406ms","start":"2026-09-07T18:20:46.653597Z","end":"2026-09-07T18:20:47.015446Z","steps":["trace[1305767582] 'agreement among raft nodes before linearized reading' (duration: 361.734903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:20:47.015531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:20:46.65347Z","time spent":"362.05043ms","remote":"127.0.0.1:48064","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-09-07T18:20:47.015554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:20:46.609993Z","time spent":"405.457692ms","remote":"127.0.0.1:48390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T18:21:32.146299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.258177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-07T18:21:32.146446Z","caller":"traceutil/trace.go:171","msg":"trace[1885776944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1199; }","duration":"171.475551ms","start":"2026-09-07T18:21:31.974943Z","end":"2026-09-07T18:21:32.146418Z","steps":["trace[1885776944] 'range keys from in-memory index tree' (duration: 170.994182ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:18.904143Z","caller":"traceutil/trace.go:171","msg":"trace[1069357863] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"189.268347ms","start":"2026-09-07T18:22:18.714847Z","end":"2026-09-07T18:22:18.904116Z","steps":["trace[1069357863] 'process raft request' (duration: 189.118304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:14.392117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.280226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:23:14.392254Z","caller":"traceutil/trace.go:171","msg":"trace[829970511] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2225; }","duration":"143.49405ms","start":"2026-09-07T18:23:14.248736Z","end":"2026-09-07T18:23:14.39223Z","steps":["trace[829970511] 'count revisions from in-memory index tree' (duration: 143.177924ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:23:14.549732Z","caller":"traceutil/trace.go:171","msg":"trace[1435119027] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"152.980111ms","start":"2026-09-07T18:23:14.396717Z","end":"2026-09-07T18:23:14.549697Z","steps":["trace[1435119027] 'process raft request' (duration: 152.725815ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:02.827126Z","caller":"traceutil/trace.go:171","msg":"trace[1572114889] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"103.915045ms","start":"2026-09-07T18:29:02.723195Z","end":"2026-09-07T18:29:02.82711Z","steps":["trace[1572114889] 'process raft request' (duration: 100.549164ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:10.005763Z","caller":"traceutil/trace.go:171","msg":"trace[919970043] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"108.378289ms","start":"2026-09-07T18:29:09.897365Z","end":"2026-09-07T18:29:10.005743Z","steps":["trace[919970043] 'process raft request' (duration: 108.254897ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:20.895893Z","caller":"traceutil/trace.go:171","msg":"trace[1313496846] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"178.423069ms","start":"2026-09-07T18:29:20.717447Z","end":"2026-09-07T18:29:20.89587Z","steps":["trace[1313496846] 'process raft request' (duration: 138.091258ms)","trace[1313496846] 'compare' (duration: 40.143578ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:21.444293Z","caller":"traceutil/trace.go:171","msg":"trace[794972361] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"230.774736ms","start":"2026-09-07T18:29:21.213489Z","end":"2026-09-07T18:29:21.444264Z","steps":["trace[794972361] 'process raft request' (duration: 230.596622ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:33.612657Z","caller":"traceutil/trace.go:171","msg":"trace[169467291] linearizableReadLoop","detail":"{readStateIndex:5921; appliedIndex:5920; }","duration":"105.530149ms","start":"2026-09-07T18:29:33.507103Z","end":"2026-09-07T18:29:33.612633Z","steps":["trace[169467291] 'read index received' (duration: 105.327865ms)","trace[169467291] 'applied index is now lower than readState.Index' (duration: 201.064µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:33.612742Z","caller":"traceutil/trace.go:171","msg":"trace[1609439271] transaction","detail":"{read_only:false; response_revision:5714; number_of_response:1; }","duration":"109.748969ms","start":"2026-09-07T18:29:33.502946Z","end":"2026-09-07T18:29:33.612695Z","steps":["trace[1609439271] 'process raft request' (duration: 109.530894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:33.612882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.756414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-07T18:29:33.612975Z","caller":"traceutil/trace.go:171","msg":"trace[1287013472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5714; }","duration":"105.880697ms","start":"2026-09-07T18:29:33.507076Z","end":"2026-09-07T18:29:33.612957Z","steps":["trace[1287013472] 'agreement among raft nodes before linearized reading' (duration: 105.650372ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:33.723036Z","caller":"traceutil/trace.go:171","msg":"trace[1738094781] linearizableReadLoop","detail":"{readStateIndex:5922; appliedIndex:5921; }","duration":"103.518907ms","start":"2026-09-07T18:29:33.61949Z","end":"2026-09-07T18:29:33.723009Z","steps":["trace[1738094781] 'read index received' (duration: 41.531697ms)","trace[1738094781] 'applied index is now lower than readState.Index' (duration: 61.9861ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:33.723213Z","caller":"traceutil/trace.go:171","msg":"trace[1295279446] transaction","detail":"{read_only:false; response_revision:5715; number_of_response:1; }","duration":"105.022059ms","start":"2026-09-07T18:29:33.618048Z","end":"2026-09-07T18:29:33.72307Z","steps":["trace[1295279446] 'process raft request' (duration: 42.955718ms)","trace[1295279446] 'compare' (duration: 61.834396ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:29:33.723249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.735751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-09-07T18:29:33.723436Z","caller":"traceutil/trace.go:171","msg":"trace[496707857] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5715; }","duration":"103.957935ms","start":"2026-09-07T18:29:33.619465Z","end":"2026-09-07T18:29:33.723423Z","steps":["trace[496707857] 'agreement among raft nodes before linearized reading' (duration: 103.644529ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:33.806104Z","caller":"traceutil/trace.go:171","msg":"trace[310008887] transaction","detail":"{read_only:false; response_revision:5716; number_of_response:1; }","duration":"177.906629ms","start":"2026-09-07T18:29:33.628171Z","end":"2026-09-07T18:29:33.806077Z","steps":["trace[310008887] 'process raft request' (duration: 177.741914ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:30:01.615497Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3008} {"level":"info","ts":"2026-09-07T18:30:01.709885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3008,"took":"92.79371ms","hash":3020200014,"current-db-size-bytes":57704448,"current-db-size":"58 MB","current-db-size-in-use-bytes":30298112,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T18:30:01.709989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3020200014,"revision":3008,"compact-revision":-1} {"level":"info","ts":"2026-09-07T18:35:01.622607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5934} {"level":"info","ts":"2026-09-07T18:35:01.756843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5934,"took":"132.198588ms","hash":3923789524,"current-db-size-bytes":57704448,"current-db-size":"58 MB","current-db-size-in-use-bytes":26226688,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T18:35:01.756934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3923789524,"revision":5934,"compact-revision":3008} {"level":"info","ts":"2026-09-07T18:36:00.390999Z","caller":"traceutil/trace.go:171","msg":"trace[245277971] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"112.109199ms","start":"2026-09-07T18:36:00.27886Z","end":"2026-09-07T18:36:00.390969Z","steps":["trace[245277971] 'process raft request' (duration: 111.928386ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:00.540826Z","caller":"traceutil/trace.go:171","msg":"trace[1190727721] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"117.326181ms","start":"2026-09-07T18:36:00.423479Z","end":"2026-09-07T18:36:00.540805Z","steps":["trace[1190727721] 'process raft request' (duration: 117.189658ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:02.038637Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022d0.wal"} {"level":"info","ts":"2026-09-07T18:36:11.983785Z","caller":"traceutil/trace.go:171","msg":"trace[1084055051] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"137.194475ms","start":"2026-09-07T18:36:11.846567Z","end":"2026-09-07T18:36:11.983762Z","steps":["trace[1084055051] 'process raft request' (duration: 137.055552ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:12.159293Z","caller":"traceutil/trace.go:171","msg":"trace[612475112] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"247.795262ms","start":"2026-09-07T18:36:11.911461Z","end":"2026-09-07T18:36:12.159256Z","steps":["trace[612475112] 'process raft request' (duration: 227.02065ms)","trace[612475112] 'compare' (duration: 20.472636ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:36:13.798316Z","caller":"traceutil/trace.go:171","msg":"trace[1981735828] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"114.849708ms","start":"2026-09-07T18:36:13.68341Z","end":"2026-09-07T18:36:13.79826Z","steps":["trace[1981735828] 'process raft request' (duration: 114.694775ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:45.072895Z","caller":"traceutil/trace.go:171","msg":"trace[1159963296] transaction","detail":"{read_only:false; response_revision:8888; number_of_response:1; }","duration":"117.322518ms","start":"2026-09-07T18:36:44.955551Z","end":"2026-09-07T18:36:45.072874Z","steps":["trace[1159963296] 'process raft request' (duration: 117.061953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:45.250203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.704243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:36:45.25037Z","caller":"traceutil/trace.go:171","msg":"trace[1721512121] transaction","detail":"{read_only:false; response_revision:8889; number_of_response:1; }","duration":"172.861356ms","start":"2026-09-07T18:36:45.077497Z","end":"2026-09-07T18:36:45.250358Z","steps":["trace[1721512121] 'process raft request' (duration: 57.785885ms)","trace[1721512121] 'compare' (duration: 114.57272ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:36:45.250416Z","caller":"traceutil/trace.go:171","msg":"trace[1530868159] transaction","detail":"{read_only:false; response_revision:8890; number_of_response:1; }","duration":"172.586331ms","start":"2026-09-07T18:36:45.0778Z","end":"2026-09-07T18:36:45.250386Z","steps":["trace[1530868159] 'process raft request' (duration: 172.474938ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:53.02997Z","caller":"traceutil/trace.go:171","msg":"trace[852293204] transaction","detail":"{read_only:false; response_revision:8930; number_of_response:1; }","duration":"114.943408ms","start":"2026-09-07T18:36:52.914976Z","end":"2026-09-07T18:36:53.02992Z","steps":["trace[852293204] 'process raft request' (duration: 114.706182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:53.248699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.124888ms","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-09-07T18:36:53.248973Z","caller":"traceutil/trace.go:171","msg":"trace[1538076427] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8930; }","duration":"105.465255ms","start":"2026-09-07T18:36:53.143487Z","end":"2026-09-07T18:36:53.248952Z","steps":["trace[1538076427] 'range keys from in-memory index tree' (duration: 105.010086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:53.248749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.940279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-07T18:36:53.249152Z","caller":"traceutil/trace.go:171","msg":"trace[1688723936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8930; }","duration":"144.316887ms","start":"2026-09-07T18:36:53.10479Z","end":"2026-09-07T18:36:53.249107Z","steps":["trace[1688723936] 'range keys from in-memory index tree' (duration: 143.728975ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:55.233353Z","caller":"traceutil/trace.go:171","msg":"trace[2124608948] linearizableReadLoop","detail":"{readStateIndex:9263; appliedIndex:9262; }","duration":"136.725304ms","start":"2026-09-07T18:36:55.096605Z","end":"2026-09-07T18:36:55.23333Z","steps":["trace[2124608948] 'read index received' (duration: 83.101667ms)","trace[2124608948] 'applied index is now lower than readState.Index' (duration: 53.622536ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:36:55.233611Z","caller":"traceutil/trace.go:171","msg":"trace[1762133637] transaction","detail":"{read_only:false; response_revision:8949; number_of_response:1; }","duration":"141.508855ms","start":"2026-09-07T18:36:55.092089Z","end":"2026-09-07T18:36:55.233598Z","steps":["trace[1762133637] 'process raft request' (duration: 87.714805ms)","trace[1762133637] 'compare' (duration: 53.220658ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:36:55.233868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.256715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-09-07T18:36:55.233914Z","caller":"traceutil/trace.go:171","msg":"trace[603747574] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8949; }","duration":"137.336267ms","start":"2026-09-07T18:36:55.096568Z","end":"2026-09-07T18:36:55.233904Z","steps":["trace[603747574] 'agreement among raft nodes before linearized reading' (duration: 137.204614ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:03.754842Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30ad0c2b6ecdd25e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T18:38:03.76196Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-07T18:38:03.762129Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-07T18:38:23.688606Z","caller":"traceutil/trace.go:171","msg":"trace[1721436780] transaction","detail":"{read_only:false; response_revision:9793; number_of_response:1; }","duration":"203.074083ms","start":"2026-09-07T18:38:23.485501Z","end":"2026-09-07T18:38:23.688575Z","steps":["trace[1721436780] 'process raft request' (duration: 202.887809ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:01.628982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8090} {"level":"info","ts":"2026-09-07T18:40:01.699897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8090,"took":"68.863028ms","hash":1262452791,"current-db-size-bytes":57704448,"current-db-size":"58 MB","current-db-size-in-use-bytes":28106752,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T18:40:01.699966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1262452791,"revision":8090,"compact-revision":5934} {"level":"info","ts":"2026-09-07T18:44:14.286363Z","caller":"traceutil/trace.go:171","msg":"trace[643709149] linearizableReadLoop","detail":"{readStateIndex:12709; appliedIndex:12708; }","duration":"125.388061ms","start":"2026-09-07T18:44:14.160946Z","end":"2026-09-07T18:44:14.286334Z","steps":["trace[643709149] 'read index received' (duration: 125.211497ms)","trace[643709149] 'applied index is now lower than readState.Index' (duration: 175.254µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:44:14.286513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.544054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:44:14.286563Z","caller":"traceutil/trace.go:171","msg":"trace[404405059] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:12288; }","duration":"125.635486ms","start":"2026-09-07T18:44:14.160903Z","end":"2026-09-07T18:44:14.286539Z","steps":["trace[404405059] 'agreement among raft nodes before linearized reading' (duration: 125.546425ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:44:14.286654Z","caller":"traceutil/trace.go:171","msg":"trace[260787662] transaction","detail":"{read_only:false; response_revision:12288; number_of_response:1; }","duration":"220.552256ms","start":"2026-09-07T18:44:14.065868Z","end":"2026-09-07T18:44:14.28642Z","steps":["trace[260787662] 'process raft request' (duration: 220.236249ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:44:14.324954Z","caller":"traceutil/trace.go:171","msg":"trace[1985303922] transaction","detail":"{read_only:false; response_revision:12289; number_of_response:1; }","duration":"130.638263ms","start":"2026-09-07T18:44:14.194289Z","end":"2026-09-07T18:44:14.324928Z","steps":["trace[1985303922] 'process raft request' (duration: 130.447269ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:45:01.636366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10468} {"level":"info","ts":"2026-09-07T18:45:01.723683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10468,"took":"85.04592ms","hash":2984625888,"current-db-size-bytes":57704448,"current-db-size":"58 MB","current-db-size-in-use-bytes":25812992,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T18:45:01.723935Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2984625888,"revision":10468,"compact-revision":8090} {"level":"info","ts":"2026-09-07T18:50:01.643968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12615} {"level":"info","ts":"2026-09-07T18:50:01.737381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12615,"took":"90.187455ms","hash":1990012675,"current-db-size-bytes":57704448,"current-db-size":"58 MB","current-db-size-in-use-bytes":27840512,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T18:50:01.737461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1990012675,"revision":12615,"compact-revision":10468} {"level":"info","ts":"2026-09-07T18:50:49.327595Z","caller":"traceutil/trace.go:171","msg":"trace[1572121272] transaction","detail":"{read_only:false; response_revision:14909; number_of_response:1; }","duration":"214.649972ms","start":"2026-09-07T18:50:49.112921Z","end":"2026-09-07T18:50:49.327571Z","steps":["trace[1572121272] 'process raft request' (duration: 214.468159ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:50:49.331216Z","caller":"traceutil/trace.go:171","msg":"trace[782007642] transaction","detail":"{read_only:false; response_revision:14910; number_of_response:1; }","duration":"207.838728ms","start":"2026-09-07T18:50:49.123354Z","end":"2026-09-07T18:50:49.331192Z","steps":["trace[782007642] 'process raft request' (duration: 207.690945ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:06.736327Z","caller":"traceutil/trace.go:171","msg":"trace[1204721280] transaction","detail":"{read_only:false; response_revision:15016; number_of_response:1; }","duration":"250.705794ms","start":"2026-09-07T18:51:06.485592Z","end":"2026-09-07T18:51:06.736297Z","steps":["trace[1204721280] 'process raft request' (duration: 250.5131ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:06.748479Z","caller":"traceutil/trace.go:171","msg":"trace[2083598883] transaction","detail":"{read_only:false; response_revision:15017; number_of_response:1; }","duration":"242.732713ms","start":"2026-09-07T18:51:06.50568Z","end":"2026-09-07T18:51:06.748412Z","steps":["trace[2083598883] 'process raft request' (duration: 242.318735ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:06.748905Z","caller":"traceutil/trace.go:171","msg":"trace[1903697103] transaction","detail":"{read_only:false; response_revision:15018; number_of_response:1; }","duration":"186.479281ms","start":"2026-09-07T18:51:06.562393Z","end":"2026-09-07T18:51:06.748873Z","steps":["trace[1903697103] 'process raft request' (duration: 185.816047ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:16.927309Z","caller":"traceutil/trace.go:171","msg":"trace[1226507223] linearizableReadLoop","detail":"{readStateIndex:15715; appliedIndex:15714; }","duration":"146.891028ms","start":"2026-09-07T18:51:16.780386Z","end":"2026-09-07T18:51:16.927277Z","steps":["trace[1226507223] 'read index received' (duration: 146.729563ms)","trace[1226507223] 'applied index is now lower than readState.Index' (duration: 160.215µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:51:16.927458Z","caller":"traceutil/trace.go:171","msg":"trace[1326705613] transaction","detail":"{read_only:false; response_revision:15193; number_of_response:1; }","duration":"221.49682ms","start":"2026-09-07T18:51:16.705901Z","end":"2026-09-07T18:51:16.927398Z","steps":["trace[1326705613] 'process raft request' (duration: 221.218483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:51:16.927519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.108862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:51:16.927997Z","caller":"traceutil/trace.go:171","msg":"trace[1666796284] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15193; }","duration":"147.620473ms","start":"2026-09-07T18:51:16.780335Z","end":"2026-09-07T18:51:16.927955Z","steps":["trace[1666796284] 'agreement among raft nodes before linearized reading' (duration: 147.082152ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:17.109002Z","caller":"traceutil/trace.go:171","msg":"trace[337125400] linearizableReadLoop","detail":"{readStateIndex:15716; appliedIndex:15715; }","duration":"181.28506ms","start":"2026-09-07T18:51:16.927692Z","end":"2026-09-07T18:51:17.108978Z","steps":["trace[337125400] 'read index received' (duration: 181.058865ms)","trace[337125400] 'applied index is now lower than readState.Index' (duration: 225.325µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:51:17.109276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.139828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-07T18:51:17.109332Z","caller":"traceutil/trace.go:171","msg":"trace[1710283580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15194; }","duration":"307.249132ms","start":"2026-09-07T18:51:16.802064Z","end":"2026-09-07T18:51:17.109313Z","steps":["trace[1710283580] 'agreement among raft nodes before linearized reading' (duration: 307.055307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:51:17.109366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.531622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ac395dc3-a853-4059-ac44-ddbff940b846\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-09-07T18:51:17.109462Z","caller":"traceutil/trace.go:171","msg":"trace[1579505588] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ac395dc3-a853-4059-ac44-ddbff940b846; range_end:; response_count:1; response_revision:15194; }","duration":"177.641454ms","start":"2026-09-07T18:51:16.931774Z","end":"2026-09-07T18:51:17.109415Z","steps":["trace[1579505588] 'agreement among raft nodes before linearized reading' (duration: 177.48178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:51:17.109376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:51:16.802053Z","time spent":"307.314183ms","remote":"127.0.0.1:48328","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":"info","ts":"2026-09-07T18:51:17.109249Z","caller":"traceutil/trace.go:171","msg":"trace[1736255896] transaction","detail":"{read_only:false; response_revision:15194; number_of_response:1; }","duration":"326.120583ms","start":"2026-09-07T18:51:16.783068Z","end":"2026-09-07T18:51:17.109188Z","steps":["trace[1736255896] 'process raft request' (duration: 325.751336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:51:17.109847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:51:16.78304Z","time spent":"326.657596ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:51:17.109918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.379645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:51:17.110161Z","caller":"traceutil/trace.go:171","msg":"trace[1035164484] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:15194; }","duration":"249.717122ms","start":"2026-09-07T18:51:16.860428Z","end":"2026-09-07T18:51:17.110145Z","steps":["trace[1035164484] 'agreement among raft nodes before linearized reading' (duration: 248.663869ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:52:48.655124Z","caller":"traceutil/trace.go:171","msg":"trace[579305661] transaction","detail":"{read_only:false; response_revision:16237; number_of_response:1; }","duration":"107.289609ms","start":"2026-09-07T18:52:48.547801Z","end":"2026-09-07T18:52:48.655091Z","steps":["trace[579305661] 'process raft request' (duration: 107.029924ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:52:48.655397Z","caller":"traceutil/trace.go:171","msg":"trace[718406141] linearizableReadLoop","detail":"{readStateIndex:16788; appliedIndex:16787; }","duration":"105.709826ms","start":"2026-09-07T18:52:48.549305Z","end":"2026-09-07T18:52:48.655015Z","steps":["trace[718406141] 'read index received' (duration: 105.470031ms)","trace[718406141] 'applied index is now lower than readState.Index' (duration: 238.475µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:52:48.655607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.263918ms","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-09-07T18:52:48.655672Z","caller":"traceutil/trace.go:171","msg":"trace[1632126457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16237; }","duration":"106.393451ms","start":"2026-09-07T18:52:48.549262Z","end":"2026-09-07T18:52:48.655655Z","steps":["trace[1632126457] 'agreement among raft nodes before linearized reading' (duration: 105.945502ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:52:49.59707Z","caller":"traceutil/trace.go:171","msg":"trace[515526763] transaction","detail":"{read_only:false; response_revision:16239; number_of_response:1; }","duration":"120.123124ms","start":"2026-09-07T18:52:49.476925Z","end":"2026-09-07T18:52:49.597048Z","steps":["trace[515526763] 'process raft request' (duration: 119.622193ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:52:49.795127Z","caller":"traceutil/trace.go:171","msg":"trace[1636473492] linearizableReadLoop","detail":"{readStateIndex:16791; appliedIndex:16790; }","duration":"172.627284ms","start":"2026-09-07T18:52:49.622461Z","end":"2026-09-07T18:52:49.795088Z","steps":["trace[1636473492] 'read index received' (duration: 121.283098ms)","trace[1636473492] 'applied index is now lower than readState.Index' (duration: 51.342975ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:52:49.795663Z","caller":"traceutil/trace.go:171","msg":"trace[1293048971] transaction","detail":"{read_only:false; response_revision:16240; number_of_response:1; }","duration":"189.632427ms","start":"2026-09-07T18:52:49.606014Z","end":"2026-09-07T18:52:49.795646Z","steps":["trace[1293048971] 'process raft request' (duration: 138.437314ms)","trace[1293048971] 'compare' (duration: 50.441196ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:52:49.796142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.660206ms","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-09-07T18:52:49.796188Z","caller":"traceutil/trace.go:171","msg":"trace[2135468050] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:16240; }","duration":"173.745518ms","start":"2026-09-07T18:52:49.622425Z","end":"2026-09-07T18:52:49.796171Z","steps":["trace[2135468050] 'agreement among raft nodes before linearized reading' (duration: 173.551084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:52:49.796475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.299383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-knq8k\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-09-07T18:52:49.79651Z","caller":"traceutil/trace.go:171","msg":"trace[1057796507] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-knq8k; range_end:; response_count:1; response_revision:16240; }","duration":"169.367325ms","start":"2026-09-07T18:52:49.627133Z","end":"2026-09-07T18:52:49.7965Z","steps":["trace[1057796507] 'agreement among raft nodes before linearized reading' (duration: 169.248523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:52:49.79686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.190435ms","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-09-07T18:52:49.796891Z","caller":"traceutil/trace.go:171","msg":"trace[1583383310] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16240; }","duration":"113.223716ms","start":"2026-09-07T18:52:49.683658Z","end":"2026-09-07T18:52:49.796882Z","steps":["trace[1583383310] 'agreement among raft nodes before linearized reading' (duration: 113.124294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:52:49.797069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.380835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:52:49.797095Z","caller":"traceutil/trace.go:171","msg":"trace[348229064] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16240; }","duration":"132.458016ms","start":"2026-09-07T18:52:49.664628Z","end":"2026-09-07T18:52:49.797086Z","steps":["trace[348229064] 'agreement among raft nodes before linearized reading' (duration: 132.415376ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:52:49.797364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.617218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-knq8k\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-09-07T18:52:49.797476Z","caller":"traceutil/trace.go:171","msg":"trace[221193910] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-knq8k; range_end:; response_count:1; response_revision:16240; }","duration":"160.734861ms","start":"2026-09-07T18:52:49.636697Z","end":"2026-09-07T18:52:49.797432Z","steps":["trace[221193910] 'agreement among raft nodes before linearized reading' (duration: 160.435744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:52:49.797797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.223923ms","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-09-07T18:52:49.797826Z","caller":"traceutil/trace.go:171","msg":"trace[581644782] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16240; }","duration":"133.279954ms","start":"2026-09-07T18:52:49.664537Z","end":"2026-09-07T18:52:49.797817Z","steps":["trace[581644782] 'agreement among raft nodes before linearized reading' (duration: 133.226193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:52:49.798059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.231335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-xp8ct\" ","response":"range_response_count:1 size:20633"} {"level":"info","ts":"2026-09-07T18:52:49.798154Z","caller":"traceutil/trace.go:171","msg":"trace[1499981244] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-xp8ct; range_end:; response_count:1; response_revision:16240; }","duration":"142.369428ms","start":"2026-09-07T18:52:49.655761Z","end":"2026-09-07T18:52:49.79813Z","steps":["trace[1499981244] 'agreement among raft nodes before linearized reading' (duration: 142.153924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:52:49.798144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.58943ms","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-09-07T18:52:49.798284Z","caller":"traceutil/trace.go:171","msg":"trace[1330524691] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16240; }","duration":"133.752285ms","start":"2026-09-07T18:52:49.66452Z","end":"2026-09-07T18:52:49.798272Z","steps":["trace[1330524691] 'agreement among raft nodes before linearized reading' (duration: 133.595591ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:55:01.656949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14620} {"level":"info","ts":"2026-09-07T18:55:01.739077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14620,"took":"78.967485ms","hash":1026453080,"current-db-size-bytes":57704448,"current-db-size":"58 MB","current-db-size-in-use-bytes":31809536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T18:55:01.739144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1026453080,"revision":14620,"compact-revision":12615} {"level":"info","ts":"2026-09-07T18:58:32.332582Z","caller":"traceutil/trace.go:171","msg":"trace[708357729] transaction","detail":"{read_only:false; response_revision:18689; number_of_response:1; }","duration":"138.63796ms","start":"2026-09-07T18:58:32.193921Z","end":"2026-09-07T18:58:32.332559Z","steps":["trace[708357729] 'process raft request' (duration: 138.232792ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:58:34.350636Z","caller":"traceutil/trace.go:171","msg":"trace[1431567833] linearizableReadLoop","detail":"{readStateIndex:19348; appliedIndex:19343; }","duration":"203.650429ms","start":"2026-09-07T18:58:34.146944Z","end":"2026-09-07T18:58:34.350594Z","steps":["trace[1431567833] 'read index received' (duration: 7.296597ms)","trace[1431567833] 'applied index is now lower than readState.Index' (duration: 196.352962ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:58:34.350846Z","caller":"traceutil/trace.go:171","msg":"trace[379830356] transaction","detail":"{read_only:false; response_revision:18711; number_of_response:1; }","duration":"207.92123ms","start":"2026-09-07T18:58:34.142912Z","end":"2026-09-07T18:58:34.350833Z","steps":["trace[379830356] 'process raft request' (duration: 147.96713ms)","trace[379830356] 'compare' (duration: 59.276886ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:58:34.351029Z","caller":"traceutil/trace.go:171","msg":"trace[1814552731] transaction","detail":"{read_only:false; response_revision:18712; number_of_response:1; }","duration":"208.003622ms","start":"2026-09-07T18:58:34.143016Z","end":"2026-09-07T18:58:34.35102Z","steps":["trace[1814552731] 'process raft request' (duration: 207.404929ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:58:34.35118Z","caller":"traceutil/trace.go:171","msg":"trace[295153244] transaction","detail":"{read_only:false; response_revision:18713; number_of_response:1; }","duration":"208.023212ms","start":"2026-09-07T18:58:34.143139Z","end":"2026-09-07T18:58:34.351162Z","steps":["trace[295153244] 'process raft request' (duration: 207.320867ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:58:34.351312Z","caller":"traceutil/trace.go:171","msg":"trace[1840622849] transaction","detail":"{read_only:false; response_revision:18714; number_of_response:1; }","duration":"207.590043ms","start":"2026-09-07T18:58:34.143709Z","end":"2026-09-07T18:58:34.351299Z","steps":["trace[1840622849] 'process raft request' (duration: 206.778166ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:58:34.351603Z","caller":"traceutil/trace.go:171","msg":"trace[1381180009] transaction","detail":"{read_only:false; response_revision:18716; number_of_response:1; }","duration":"201.739087ms","start":"2026-09-07T18:58:34.149831Z","end":"2026-09-07T18:58:34.351571Z","steps":["trace[1381180009] 'process raft request' (duration: 200.713896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:58:34.351913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.943666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-09-07T18:58:34.352023Z","caller":"traceutil/trace.go:171","msg":"trace[808319359] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:18716; }","duration":"205.080809ms","start":"2026-09-07T18:58:34.146892Z","end":"2026-09-07T18:58:34.351973Z","steps":["trace[808319359] 'agreement among raft nodes before linearized reading' (duration: 204.890455ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:58:34.351937Z","caller":"traceutil/trace.go:171","msg":"trace[2019043028] transaction","detail":"{read_only:false; response_revision:18715; number_of_response:1; }","duration":"208.124494ms","start":"2026-09-07T18:58:34.1438Z","end":"2026-09-07T18:58:34.351924Z","steps":["trace[2019043028] 'process raft request' (duration: 206.713604ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:58:39.748921Z","caller":"traceutil/trace.go:171","msg":"trace[1305235014] linearizableReadLoop","detail":"{readStateIndex:19417; appliedIndex:19416; }","duration":"106.99451ms","start":"2026-09-07T18:58:39.641904Z","end":"2026-09-07T18:58:39.748898Z","steps":["trace[1305235014] 'read index received' (duration: 106.787966ms)","trace[1305235014] 'applied index is now lower than readState.Index' (duration: 205.664µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:58:39.749904Z","caller":"traceutil/trace.go:171","msg":"trace[1122175015] transaction","detail":"{read_only:false; response_revision:18781; number_of_response:1; }","duration":"120.113323ms","start":"2026-09-07T18:58:39.629775Z","end":"2026-09-07T18:58:39.749888Z","steps":["trace[1122175015] 'process raft request' (duration: 118.986399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:58:39.750469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.556254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-07T18:58:39.750572Z","caller":"traceutil/trace.go:171","msg":"trace[1401155821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18781; }","duration":"108.694086ms","start":"2026-09-07T18:58:39.641861Z","end":"2026-09-07T18:58:39.750555Z","steps":["trace[1401155821] 'agreement among raft nodes before linearized reading' (duration: 108.503332ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:58:39.887242Z","caller":"traceutil/trace.go:171","msg":"trace[140112041] transaction","detail":"{read_only:false; response_revision:18782; number_of_response:1; }","duration":"129.363901ms","start":"2026-09-07T18:58:39.757821Z","end":"2026-09-07T18:58:39.887185Z","steps":["trace[140112041] 'process raft request' (duration: 92.839686ms)","trace[140112041] 'compare' (duration: 36.29294ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:58:40.024349Z","caller":"traceutil/trace.go:171","msg":"trace[976570286] transaction","detail":"{read_only:false; response_revision:18784; number_of_response:1; }","duration":"109.077926ms","start":"2026-09-07T18:58:39.915243Z","end":"2026-09-07T18:58:40.02432Z","steps":["trace[976570286] 'process raft request' (duration: 108.927302ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:59:30.183241Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30ad0c2b6ecdd25e","local-member-applied-index":20005,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T18:59:30.205424Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20005} {"level":"info","ts":"2026-09-07T18:59:30.205522Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15005} {"level":"info","ts":"2026-09-07T19:00:01.66315Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17059} {"level":"info","ts":"2026-09-07T19:00:01.759028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17059,"took":"92.897668ms","hash":1441977527,"current-db-size-bytes":57704448,"current-db-size":"58 MB","current-db-size-in-use-bytes":34123776,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-07T19:00:01.759115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1441977527,"revision":17059,"compact-revision":14620} {"level":"info","ts":"2026-09-07T19:00:03.687997Z","caller":"traceutil/trace.go:171","msg":"trace[348620824] transaction","detail":"{read_only:false; response_revision:19743; number_of_response:1; }","duration":"112.04899ms","start":"2026-09-07T19:00:03.575927Z","end":"2026-09-07T19:00:03.687976Z","steps":["trace[348620824] 'process raft request' (duration: 111.826845ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:05:00.155583Z","caller":"traceutil/trace.go:171","msg":"trace[483130248] linearizableReadLoop","detail":"{readStateIndex:22467; appliedIndex:22466; }","duration":"265.236578ms","start":"2026-09-07T19:04:59.890324Z","end":"2026-09-07T19:05:00.155561Z","steps":["trace[483130248] 'read index received' (duration: 265.019884ms)","trace[483130248] 'applied index is now lower than readState.Index' (duration: 216.084µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:05:00.155654Z","caller":"traceutil/trace.go:171","msg":"trace[289607416] transaction","detail":"{read_only:false; response_revision:21736; number_of_response:1; }","duration":"266.269631ms","start":"2026-09-07T19:04:59.889346Z","end":"2026-09-07T19:05:00.155616Z","steps":["trace[289607416] 'process raft request' (duration: 266.069936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:05:00.155801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.454793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-07T19:05:00.155838Z","caller":"traceutil/trace.go:171","msg":"trace[56401245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21736; }","duration":"265.546175ms","start":"2026-09-07T19:04:59.890282Z","end":"2026-09-07T19:05:00.155828Z","steps":["trace[56401245] 'agreement among raft nodes before linearized reading' (duration: 265.388212ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:05:00.288286Z","caller":"traceutil/trace.go:171","msg":"trace[1215427504] transaction","detail":"{read_only:false; response_revision:21737; number_of_response:1; }","duration":"129.933292ms","start":"2026-09-07T19:05:00.158298Z","end":"2026-09-07T19:05:00.288231Z","steps":["trace[1215427504] 'process raft request' (duration: 89.797009ms)","trace[1215427504] 'compare' (duration: 39.951919ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:05:00.289202Z","caller":"traceutil/trace.go:171","msg":"trace[495234917] transaction","detail":"{read_only:false; response_revision:21739; number_of_response:1; }","duration":"128.581372ms","start":"2026-09-07T19:05:00.160591Z","end":"2026-09-07T19:05:00.289172Z","steps":["trace[495234917] 'process raft request' (duration: 128.434309ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:05:00.289754Z","caller":"traceutil/trace.go:171","msg":"trace[573500944] transaction","detail":"{read_only:false; response_revision:21738; number_of_response:1; }","duration":"130.320381ms","start":"2026-09-07T19:05:00.158775Z","end":"2026-09-07T19:05:00.289095Z","steps":["trace[573500944] 'process raft request' (duration: 130.069816ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:05:01.704309Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19727} {"level":"info","ts":"2026-09-07T19:05:01.81879Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19727,"took":"106.540309ms","hash":1299740579,"current-db-size-bytes":57704448,"current-db-size":"58 MB","current-db-size-in-use-bytes":30310400,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T19:05:01.818844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1299740579,"revision":19727,"compact-revision":17059} {"level":"warn","ts":"2026-09-07T19:05:04.449726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.534889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-07T19:05:04.449823Z","caller":"traceutil/trace.go:171","msg":"trace[103495153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21787; }","duration":"140.679212ms","start":"2026-09-07T19:05:04.309121Z","end":"2026-09-07T19:05:04.4498Z","steps":["trace[103495153] 'range keys from in-memory index tree' (duration: 140.339755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:05:04.449821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.495436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-07T19:05:04.449922Z","caller":"traceutil/trace.go:171","msg":"trace[1288454466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21787; }","duration":"145.66097ms","start":"2026-09-07T19:05:04.304238Z","end":"2026-09-07T19:05:04.449899Z","steps":["trace[1288454466] 'range keys from in-memory index tree' (duration: 145.291092ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:05:04.640766Z","caller":"traceutil/trace.go:171","msg":"trace[192986781] linearizableReadLoop","detail":"{readStateIndex:22523; appliedIndex:22522; }","duration":"118.562167ms","start":"2026-09-07T19:05:04.522175Z","end":"2026-09-07T19:05:04.640737Z","steps":["trace[192986781] 'read index received' (duration: 75.450742ms)","trace[192986781] 'applied index is now lower than readState.Index' (duration: 43.110265ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:05:04.640813Z","caller":"traceutil/trace.go:171","msg":"trace[338095063] transaction","detail":"{read_only:false; response_revision:21790; number_of_response:1; }","duration":"185.315571ms","start":"2026-09-07T19:05:04.455457Z","end":"2026-09-07T19:05:04.640773Z","steps":["trace[338095063] 'process raft request' (duration: 142.191565ms)","trace[338095063] 'compare' (duration: 42.931142ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:05:04.641008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.811493ms","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-09-07T19:05:04.641069Z","caller":"traceutil/trace.go:171","msg":"trace[1964495145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21790; }","duration":"118.915064ms","start":"2026-09-07T19:05:04.522139Z","end":"2026-09-07T19:05:04.641054Z","steps":["trace[1964495145] 'agreement among raft nodes before linearized reading' (duration: 118.74016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:05:04.641069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.69901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T19:05:04.641115Z","caller":"traceutil/trace.go:171","msg":"trace[433421392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21790; }","duration":"118.782001ms","start":"2026-09-07T19:05:04.52232Z","end":"2026-09-07T19:05:04.641102Z","steps":["trace[433421392] 'agreement among raft nodes before linearized reading' (duration: 118.592567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:05:04.954535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.698736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:05:04.95464Z","caller":"traceutil/trace.go:171","msg":"trace[1615214483] transaction","detail":"{read_only:false; response_revision:21791; number_of_response:1; }","duration":"309.152692ms","start":"2026-09-07T19:05:04.645467Z","end":"2026-09-07T19:05:04.95462Z","steps":["trace[1615214483] 'process raft request' (duration: 159.261912ms)","trace[1615214483] 'compare' (duration: 149.463851ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:05:04.954712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:05:04.645451Z","time spent":"309.218923ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:05:05.005435Z","caller":"traceutil/trace.go:171","msg":"trace[945017291] transaction","detail":"{read_only:false; response_revision:21792; number_of_response:1; }","duration":"358.777427ms","start":"2026-09-07T19:05:04.646605Z","end":"2026-09-07T19:05:05.005383Z","steps":["trace[945017291] 'process raft request' (duration: 358.581423ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:05:05.005627Z","caller":"traceutil/trace.go:171","msg":"trace[990188359] transaction","detail":"{read_only:false; response_revision:21793; number_of_response:1; }","duration":"275.527129ms","start":"2026-09-07T19:05:04.730072Z","end":"2026-09-07T19:05:05.005599Z","steps":["trace[990188359] 'process raft request' (duration: 275.261573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:05:05.005663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:05:04.64658Z","time spent":"358.999602ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:05:05.005617Z","caller":"traceutil/trace.go:171","msg":"trace[597539263] linearizableReadLoop","detail":"{readStateIndex:22525; appliedIndex:22523; }","duration":"351.45676ms","start":"2026-09-07T19:05:04.654115Z","end":"2026-09-07T19:05:05.005572Z","steps":["trace[597539263] 'read index received' (duration: 150.753359ms)","trace[597539263] 'applied index is now lower than readState.Index' (duration: 200.701131ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:05:05.006332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.652994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:05:05.006461Z","caller":"traceutil/trace.go:171","msg":"trace[1318036826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21793; }","duration":"352.35304ms","start":"2026-09-07T19:05:04.654087Z","end":"2026-09-07T19:05:05.00644Z","steps":["trace[1318036826] 'agreement among raft nodes before linearized reading' (duration: 351.597653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:05:05.006514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:05:04.654072Z","time spent":"352.426852ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-07T19:05:05.01535Z","caller":"traceutil/trace.go:171","msg":"trace[1115200854] transaction","detail":"{read_only:false; response_revision:21794; number_of_response:1; }","duration":"186.996718ms","start":"2026-09-07T19:05:04.828287Z","end":"2026-09-07T19:05:05.015284Z","steps":["trace[1115200854] 'process raft request' (duration: 186.772053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:05:05.015649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.086214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-09-07T19:05:05.015742Z","caller":"traceutil/trace.go:171","msg":"trace[1212240840] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:21795; }","duration":"177.209417ms","start":"2026-09-07T19:05:04.838493Z","end":"2026-09-07T19:05:05.015702Z","steps":["trace[1212240840] 'agreement among raft nodes before linearized reading' (duration: 177.042823ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:09:22.413022Z","caller":"traceutil/trace.go:171","msg":"trace[430635691] transaction","detail":"{read_only:false; response_revision:24376; number_of_response:1; }","duration":"184.274099ms","start":"2026-09-07T19:09:22.228722Z","end":"2026-09-07T19:09:22.412996Z","steps":["trace[430635691] 'process raft request' (duration: 180.988938ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:09:22.413048Z","caller":"traceutil/trace.go:171","msg":"trace[721659708] linearizableReadLoop","detail":"{readStateIndex:25185; appliedIndex:25184; }","duration":"145.116948ms","start":"2026-09-07T19:09:22.267903Z","end":"2026-09-07T19:09:22.413019Z","steps":["trace[721659708] 'read index received' (duration: 141.817616ms)","trace[721659708] 'applied index is now lower than readState.Index' (duration: 3.298011ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:09:22.414891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.978018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-07T19:09:22.414946Z","caller":"traceutil/trace.go:171","msg":"trace[1020619846] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24377; }","duration":"147.08787ms","start":"2026-09-07T19:09:22.267846Z","end":"2026-09-07T19:09:22.414934Z","steps":["trace[1020619846] 'agreement among raft nodes before linearized reading' (duration: 145.328073ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:10:01.715537Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21775} {"level":"info","ts":"2026-09-07T19:10:01.845049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21775,"took":"125.659999ms","hash":3647503600,"current-db-size-bytes":57704448,"current-db-size":"58 MB","current-db-size-in-use-bytes":43126784,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-07T19:10:01.845124Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3647503600,"revision":21775,"compact-revision":19727} {"level":"info","ts":"2026-09-07T19:11:11.89937Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066e5.wal"} {"level":"info","ts":"2026-09-07T19:12:45.016182Z","caller":"traceutil/trace.go:171","msg":"trace[331037599] linearizableReadLoop","detail":"{readStateIndex:27045; appliedIndex:27044; }","duration":"281.63157ms","start":"2026-09-07T19:12:44.734524Z","end":"2026-09-07T19:12:45.016156Z","steps":["trace[331037599] 'read index received' (duration: 281.475256ms)","trace[331037599] 'applied index is now lower than readState.Index' (duration: 155.154µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:12:45.016283Z","caller":"traceutil/trace.go:171","msg":"trace[368947260] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"299.570785ms","start":"2026-09-07T19:12:44.716668Z","end":"2026-09-07T19:12:45.016238Z","steps":["trace[368947260] 'process raft request' (duration: 299.258319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:12:45.016335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.782543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:12:45.016374Z","caller":"traceutil/trace.go:171","msg":"trace[1016529676] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26187; }","duration":"281.918046ms","start":"2026-09-07T19:12:44.734445Z","end":"2026-09-07T19:12:45.016363Z","steps":["trace[1016529676] 'agreement among raft nodes before linearized reading' (duration: 281.817444ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:45.170487Z","caller":"traceutil/trace.go:171","msg":"trace[456744487] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"329.971169ms","start":"2026-09-07T19:12:44.840493Z","end":"2026-09-07T19:12:45.170464Z","steps":["trace[456744487] 'process raft request' (duration: 269.680894ms)","trace[456744487] 'compare' (duration: 60.08853ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:12:45.171264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:12:44.840472Z","time spent":"330.691654ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:12:45.170807Z","caller":"traceutil/trace.go:171","msg":"trace[1837455203] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"211.660927ms","start":"2026-09-07T19:12:44.959127Z","end":"2026-09-07T19:12:45.170788Z","steps":["trace[1837455203] 'process raft request' (duration: 211.291159ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:45.170895Z","caller":"traceutil/trace.go:171","msg":"trace[1688632576] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"205.073776ms","start":"2026-09-07T19:12:44.965805Z","end":"2026-09-07T19:12:45.170879Z","steps":["trace[1688632576] 'process raft request' (duration: 205.025605ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:45.170932Z","caller":"traceutil/trace.go:171","msg":"trace[730136116] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"211.696838ms","start":"2026-09-07T19:12:44.959227Z","end":"2026-09-07T19:12:45.170924Z","steps":["trace[730136116] 'process raft request' (duration: 211.370551ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:45.324541Z","caller":"traceutil/trace.go:171","msg":"trace[499774851] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"146.259842ms","start":"2026-09-07T19:12:45.178256Z","end":"2026-09-07T19:12:45.324516Z","steps":["trace[499774851] 'process raft request' (duration: 132.63045ms)","trace[499774851] 'compare' (duration: 13.470609ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:12:45.324752Z","caller":"traceutil/trace.go:171","msg":"trace[1479038951] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"145.945927ms","start":"2026-09-07T19:12:45.178776Z","end":"2026-09-07T19:12:45.324722Z","steps":["trace[1479038951] 'process raft request' (duration: 145.701931ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:49.16379Z","caller":"traceutil/trace.go:171","msg":"trace[1842451951] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"119.282083ms","start":"2026-09-07T19:12:49.044474Z","end":"2026-09-07T19:12:49.163756Z","steps":["trace[1842451951] 'process raft request' (duration: 119.054338ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:53.755148Z","caller":"traceutil/trace.go:171","msg":"trace[1744813183] linearizableReadLoop","detail":"{readStateIndex:27114; appliedIndex:27112; }","duration":"113.496939ms","start":"2026-09-07T19:12:53.641622Z","end":"2026-09-07T19:12:53.755119Z","steps":["trace[1744813183] 'read index received' (duration: 69.441822ms)","trace[1744813183] 'applied index is now lower than readState.Index' (duration: 44.054087ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:12:53.755237Z","caller":"traceutil/trace.go:171","msg":"trace[799172744] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"120.097991ms","start":"2026-09-07T19:12:53.635107Z","end":"2026-09-07T19:12:53.755205Z","steps":["trace[799172744] 'process raft request' (duration: 119.846546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:12:53.755465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.816596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-07T19:12:53.755364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.050022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:12:53.755554Z","caller":"traceutil/trace.go:171","msg":"trace[1452930312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26254; }","duration":"113.980079ms","start":"2026-09-07T19:12:53.641549Z","end":"2026-09-07T19:12:53.755529Z","steps":["trace[1452930312] 'agreement among raft nodes before linearized reading' (duration: 113.702643ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:53.755566Z","caller":"traceutil/trace.go:171","msg":"trace[1856906996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26254; }","duration":"102.316217ms","start":"2026-09-07T19:12:53.653237Z","end":"2026-09-07T19:12:53.755553Z","steps":["trace[1856906996] 'agreement among raft nodes before linearized reading' (duration: 102.017561ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:55.588049Z","caller":"traceutil/trace.go:171","msg":"trace[800887145] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"190.166145ms","start":"2026-09-07T19:12:55.397846Z","end":"2026-09-07T19:12:55.588012Z","steps":["trace[800887145] 'process raft request' (duration: 141.764816ms)","trace[800887145] 'compare' (duration: 48.067192ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:12:56.642153Z","caller":"traceutil/trace.go:171","msg":"trace[2122846354] transaction","detail":"{read_only:false; response_revision:26273; number_of_response:1; }","duration":"113.618082ms","start":"2026-09-07T19:12:56.528505Z","end":"2026-09-07T19:12:56.642123Z","steps":["trace[2122846354] 'process raft request' (duration: 113.352215ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:56.652003Z","caller":"traceutil/trace.go:171","msg":"trace[1819902505] transaction","detail":"{read_only:false; response_revision:26274; number_of_response:1; }","duration":"101.01665ms","start":"2026-09-07T19:12:56.550963Z","end":"2026-09-07T19:12:56.651979Z","steps":["trace[1819902505] 'process raft request' (duration: 100.744094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:12:56.652025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.068932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-09-07T19:12:56.652218Z","caller":"traceutil/trace.go:171","msg":"trace[419428126] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26274; }","duration":"102.308247ms","start":"2026-09-07T19:12:56.54989Z","end":"2026-09-07T19:12:56.652198Z","steps":["trace[419428126] 'agreement among raft nodes before linearized reading' (duration: 101.95804ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:15.640115Z","caller":"traceutil/trace.go:171","msg":"trace[1313915521] transaction","detail":"{read_only:false; response_revision:26407; number_of_response:1; }","duration":"118.000721ms","start":"2026-09-07T19:13:15.522062Z","end":"2026-09-07T19:13:15.640063Z","steps":["trace[1313915521] 'process raft request' (duration: 42.248436ms)","trace[1313915521] 'compare' (duration: 74.944448ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:16.95087Z","caller":"traceutil/trace.go:171","msg":"trace[61168615] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"209.258779ms","start":"2026-09-07T19:13:16.741579Z","end":"2026-09-07T19:13:16.950838Z","steps":["trace[61168615] 'process raft request' (duration: 209.064495ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:16.963168Z","caller":"traceutil/trace.go:171","msg":"trace[291678239] linearizableReadLoop","detail":"{readStateIndex:27285; appliedIndex:27283; }","duration":"128.138639ms","start":"2026-09-07T19:13:16.835006Z","end":"2026-09-07T19:13:16.963145Z","steps":["trace[291678239] 'read index received' (duration: 115.608891ms)","trace[291678239] 'applied index is now lower than readState.Index' (duration: 12.528578ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:16.963268Z","caller":"traceutil/trace.go:171","msg":"trace[1142395773] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"129.255564ms","start":"2026-09-07T19:13:16.833961Z","end":"2026-09-07T19:13:16.963217Z","steps":["trace[1142395773] 'process raft request' (duration: 129.051789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:16.963429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.403145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-09-07T19:13:16.96354Z","caller":"traceutil/trace.go:171","msg":"trace[576176772] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26420; }","duration":"128.577159ms","start":"2026-09-07T19:13:16.834942Z","end":"2026-09-07T19:13:16.963519Z","steps":["trace[576176772] 'agreement among raft nodes before linearized reading' (duration: 128.299033ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:17.82865Z","caller":"traceutil/trace.go:171","msg":"trace[1442845467] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"104.520783ms","start":"2026-09-07T19:13:17.724096Z","end":"2026-09-07T19:13:17.828617Z","steps":["trace[1442845467] 'process raft request' (duration: 104.257657ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:18.968708Z","caller":"traceutil/trace.go:171","msg":"trace[83598221] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"109.436438ms","start":"2026-09-07T19:13:18.859194Z","end":"2026-09-07T19:13:18.96863Z","steps":["trace[83598221] 'process raft request' (duration: 109.210243ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:19.975949Z","caller":"traceutil/trace.go:171","msg":"trace[1973532818] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"142.66165ms","start":"2026-09-07T19:13:19.833245Z","end":"2026-09-07T19:13:19.975906Z","steps":["trace[1973532818] 'process raft request' (duration: 131.242155ms)","trace[1973532818] 'compare' (duration: 11.042287ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:19.976187Z","caller":"traceutil/trace.go:171","msg":"trace[1871014864] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"142.773533ms","start":"2026-09-07T19:13:19.83339Z","end":"2026-09-07T19:13:19.976164Z","steps":["trace[1871014864] 'process raft request' (duration: 142.403565ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:19.97652Z","caller":"traceutil/trace.go:171","msg":"trace[2000885104] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"140.170177ms","start":"2026-09-07T19:13:19.836318Z","end":"2026-09-07T19:13:19.976488Z","steps":["trace[2000885104] 'process raft request' (duration: 139.753648ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:20.168859Z","caller":"traceutil/trace.go:171","msg":"trace[19459229] transaction","detail":"{read_only:false; response_revision:26445; number_of_response:1; }","duration":"115.786025ms","start":"2026-09-07T19:13:20.053021Z","end":"2026-09-07T19:13:20.168807Z","steps":["trace[19459229] 'process raft request' (duration: 115.582721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:20.430319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.007989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:20.430418Z","caller":"traceutil/trace.go:171","msg":"trace[545242027] linearizableReadLoop","detail":"{readStateIndex:27311; appliedIndex:27310; }","duration":"153.680137ms","start":"2026-09-07T19:13:20.27672Z","end":"2026-09-07T19:13:20.430401Z","steps":["trace[545242027] 'read index received' (duration: 9.417553ms)","trace[545242027] 'applied index is now lower than readState.Index' (duration: 144.261344ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:20.430468Z","caller":"traceutil/trace.go:171","msg":"trace[379697053] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"174.501924ms","start":"2026-09-07T19:13:20.255931Z","end":"2026-09-07T19:13:20.430433Z","steps":["trace[379697053] 'process raft request' (duration: 30.28863ms)","trace[379697053] 'compare' (duration: 143.884727ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:20.430598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.80824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T19:13:20.430623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.452368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T19:13:20.430635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.752942ms","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":"info","ts":"2026-09-07T19:13:20.430692Z","caller":"traceutil/trace.go:171","msg":"trace[703087919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26446; }","duration":"123.564291ms","start":"2026-09-07T19:13:20.30711Z","end":"2026-09-07T19:13:20.430675Z","steps":["trace[703087919] 'agreement among raft nodes before linearized reading' (duration: 123.448408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:20.430708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.13219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-07T19:13:20.430642Z","caller":"traceutil/trace.go:171","msg":"trace[1737183933] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26446; }","duration":"153.942363ms","start":"2026-09-07T19:13:20.276687Z","end":"2026-09-07T19:13:20.430629Z","steps":["trace[1737183933] 'agreement among raft nodes before linearized reading' (duration: 153.81105ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:20.430721Z","caller":"traceutil/trace.go:171","msg":"trace[1948539941] 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:26446; }","duration":"101.899565ms","start":"2026-09-07T19:13:20.328803Z","end":"2026-09-07T19:13:20.430702Z","steps":["trace[1948539941] 'agreement among raft nodes before linearized reading' (duration: 101.735112ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:20.43076Z","caller":"traceutil/trace.go:171","msg":"trace[711364152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26446; }","duration":"150.230102ms","start":"2026-09-07T19:13:20.280517Z","end":"2026-09-07T19:13:20.430747Z","steps":["trace[711364152] 'agreement among raft nodes before linearized reading' (duration: 150.015738ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:21.904293Z","caller":"traceutil/trace.go:171","msg":"trace[214484516] transaction","detail":"{read_only:false; response_revision:26451; number_of_response:1; }","duration":"136.846316ms","start":"2026-09-07T19:13:21.767411Z","end":"2026-09-07T19:13:21.904257Z","steps":["trace[214484516] 'process raft request' (duration: 121.961687ms)","trace[214484516] 'compare' (duration: 14.780157ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:22.638104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.532106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:22.638193Z","caller":"traceutil/trace.go:171","msg":"trace[862815415] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:26458; }","duration":"195.660458ms","start":"2026-09-07T19:13:22.442513Z","end":"2026-09-07T19:13:22.638173Z","steps":["trace[862815415] 'count revisions from in-memory index tree' (duration: 195.485475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.638359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.070068ms","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-09-07T19:13:22.638413Z","caller":"traceutil/trace.go:171","msg":"trace[1765356579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26458; }","duration":"162.158249ms","start":"2026-09-07T19:13:22.476242Z","end":"2026-09-07T19:13:22.6384Z","steps":["trace[1765356579] 'range keys from in-memory index tree' (duration: 161.930873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.63857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.82084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:22.638628Z","caller":"traceutil/trace.go:171","msg":"trace[313958370] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26458; }","duration":"208.922993ms","start":"2026-09-07T19:13:22.42969Z","end":"2026-09-07T19:13:22.638613Z","steps":["trace[313958370] 'count revisions from in-memory index tree' (duration: 208.742108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.638643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.662469ms","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-09-07T19:13:22.638868Z","caller":"traceutil/trace.go:171","msg":"trace[254569686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26458; }","duration":"203.933666ms","start":"2026-09-07T19:13:22.434924Z","end":"2026-09-07T19:13:22.638858Z","steps":["trace[254569686] 'range keys from in-memory index tree' (duration: 202.834482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:24.136677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.828345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:24.136807Z","caller":"traceutil/trace.go:171","msg":"trace[1762254336] transaction","detail":"{read_only:false; response_revision:26465; number_of_response:1; }","duration":"226.692483ms","start":"2026-09-07T19:13:23.910088Z","end":"2026-09-07T19:13:24.13678Z","steps":["trace[1762254336] 'process raft request' (duration: 96.649544ms)","trace[1762254336] 'compare' (duration: 129.492028ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:24.230055Z","caller":"traceutil/trace.go:171","msg":"trace[1565674710] transaction","detail":"{read_only:false; response_revision:26468; number_of_response:1; }","duration":"226.531461ms","start":"2026-09-07T19:13:24.003498Z","end":"2026-09-07T19:13:24.23003Z","steps":["trace[1565674710] 'process raft request' (duration: 226.4866ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:24.230071Z","caller":"traceutil/trace.go:171","msg":"trace[1622453192] transaction","detail":"{read_only:false; response_revision:26466; number_of_response:1; }","duration":"299.402454ms","start":"2026-09-07T19:13:23.930649Z","end":"2026-09-07T19:13:24.230051Z","steps":["trace[1622453192] 'process raft request' (duration: 299.169688ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:24.230152Z","caller":"traceutil/trace.go:171","msg":"trace[1498755586] transaction","detail":"{read_only:false; response_revision:26467; number_of_response:1; }","duration":"255.278737ms","start":"2026-09-07T19:13:23.974841Z","end":"2026-09-07T19:13:24.23012Z","steps":["trace[1498755586] 'process raft request' (duration: 255.099833ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:24.341078Z","caller":"traceutil/trace.go:171","msg":"trace[65496604] transaction","detail":"{read_only:false; response_revision:26469; number_of_response:1; }","duration":"255.552543ms","start":"2026-09-07T19:13:24.085479Z","end":"2026-09-07T19:13:24.341032Z","steps":["trace[65496604] 'process raft request' (duration: 185.214894ms)","trace[65496604] 'compare' (duration: 69.885329ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:24.341167Z","caller":"traceutil/trace.go:171","msg":"trace[636261182] transaction","detail":"{read_only:false; response_revision:26470; number_of_response:1; }","duration":"255.500692ms","start":"2026-09-07T19:13:24.085576Z","end":"2026-09-07T19:13:24.341077Z","steps":["trace[636261182] 'process raft request' (duration: 255.307078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:24.341415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.800067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T19:13:24.341481Z","caller":"traceutil/trace.go:171","msg":"trace[1165752192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26471; }","duration":"206.92932ms","start":"2026-09-07T19:13:24.134542Z","end":"2026-09-07T19:13:24.341471Z","steps":["trace[1165752192] 'agreement among raft nodes before linearized reading' (duration: 206.737856ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:24.341226Z","caller":"traceutil/trace.go:171","msg":"trace[2136643708] linearizableReadLoop","detail":"{readStateIndex:27337; appliedIndex:27331; }","duration":"206.597372ms","start":"2026-09-07T19:13:24.134601Z","end":"2026-09-07T19:13:24.341198Z","steps":["trace[2136643708] 'read index received' (duration: 95.272703ms)","trace[2136643708] 'applied index is now lower than readState.Index' (duration: 111.323719ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:24.34145Z","caller":"traceutil/trace.go:171","msg":"trace[1989969011] transaction","detail":"{read_only:false; response_revision:26471; number_of_response:1; }","duration":"232.12352ms","start":"2026-09-07T19:13:24.109202Z","end":"2026-09-07T19:13:24.341325Z","steps":["trace[1989969011] 'process raft request' (duration: 231.818713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:24.341633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.213593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:24.341664Z","caller":"traceutil/trace.go:171","msg":"trace[114036901] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26471; }","duration":"109.287575ms","start":"2026-09-07T19:13:24.232368Z","end":"2026-09-07T19:13:24.341655Z","steps":["trace[114036901] 'agreement among raft nodes before linearized reading' (duration: 109.234344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:24.342153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.502378ms","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-09-07T19:13:24.342223Z","caller":"traceutil/trace.go:171","msg":"trace[695259593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26471; }","duration":"123.008809ms","start":"2026-09-07T19:13:24.219193Z","end":"2026-09-07T19:13:24.342201Z","steps":["trace[695259593] 'agreement among raft nodes before linearized reading' (duration: 122.401086ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:24.485933Z","caller":"traceutil/trace.go:171","msg":"trace[614420938] transaction","detail":"{read_only:false; response_revision:26472; number_of_response:1; }","duration":"138.650345ms","start":"2026-09-07T19:13:24.347242Z","end":"2026-09-07T19:13:24.485892Z","steps":["trace[614420938] 'process raft request' (duration: 113.053616ms)","trace[614420938] 'compare' (duration: 25.462926ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:24.514718Z","caller":"traceutil/trace.go:171","msg":"trace[420699888] transaction","detail":"{read_only:false; response_revision:26473; number_of_response:1; }","duration":"167.443662ms","start":"2026-09-07T19:13:24.347246Z","end":"2026-09-07T19:13:24.51469Z","steps":["trace[420699888] 'process raft request' (duration: 167.31987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:25.417532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.648942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:25.417628Z","caller":"traceutil/trace.go:171","msg":"trace[1148248790] linearizableReadLoop","detail":"{readStateIndex:27343; appliedIndex:27342; }","duration":"100.754222ms","start":"2026-09-07T19:13:25.316855Z","end":"2026-09-07T19:13:25.417609Z","steps":["trace[1148248790] 'read index received' (duration: 36.371µs)","trace[1148248790] 'applied index is now lower than readState.Index' (duration: 100.716391ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:25.417725Z","caller":"traceutil/trace.go:171","msg":"trace[494192026] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"217.622579ms","start":"2026-09-07T19:13:25.200073Z","end":"2026-09-07T19:13:25.417696Z","steps":["trace[494192026] 'process raft request' (duration: 60.681202ms)","trace[494192026] 'compare' (duration: 156.472947ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:25.417817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.959557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.76\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-07T19:13:25.417851Z","caller":"traceutil/trace.go:171","msg":"trace[2051286307] range","detail":"{range_begin:/registry/masterleases/162.253.55.76; range_end:; response_count:1; response_revision:26477; }","duration":"101.028079ms","start":"2026-09-07T19:13:25.316814Z","end":"2026-09-07T19:13:25.417842Z","steps":["trace[2051286307] 'agreement among raft nodes before linearized reading' (duration: 100.881495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.154383Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15158729854930530755,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-07T19:13:26.286469Z","caller":"traceutil/trace.go:171","msg":"trace[946720683] linearizableReadLoop","detail":"{readStateIndex:27347; appliedIndex:27345; }","duration":"632.082372ms","start":"2026-09-07T19:13:25.654335Z","end":"2026-09-07T19:13:26.286417Z","steps":["trace[946720683] 'read index received' (duration: 544.019862ms)","trace[946720683] 'applied index is now lower than readState.Index' (duration: 88.06112ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:26.286505Z","caller":"traceutil/trace.go:171","msg":"trace[2033659644] transaction","detail":"{read_only:false; response_revision:26479; number_of_response:1; }","duration":"683.470044ms","start":"2026-09-07T19:13:25.603Z","end":"2026-09-07T19:13:26.28647Z","steps":["trace[2033659644] 'process raft request' (duration: 595.659979ms)","trace[2033659644] 'compare' (duration: 87.14774ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:26.286615Z","caller":"traceutil/trace.go:171","msg":"trace[259511309] transaction","detail":"{read_only:false; response_revision:26480; number_of_response:1; }","duration":"144.412228ms","start":"2026-09-07T19:13:26.142184Z","end":"2026-09-07T19:13:26.286597Z","steps":["trace[259511309] 'process raft request' (duration: 144.132203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.286656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:25.602975Z","time spent":"683.594446ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:13:26.288501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.139865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:26.288589Z","caller":"traceutil/trace.go:171","msg":"trace[1723148437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26480; }","duration":"634.285628ms","start":"2026-09-07T19:13:25.654282Z","end":"2026-09-07T19:13:26.288568Z","steps":["trace[1723148437] 'agreement among raft nodes before linearized reading' (duration: 634.114394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.288636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:25.654264Z","time spent":"634.361329ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T19:13:26.318072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.082007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:26.318153Z","caller":"traceutil/trace.go:171","msg":"trace[873282174] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26482; }","duration":"539.220099ms","start":"2026-09-07T19:13:25.778913Z","end":"2026-09-07T19:13:26.318134Z","steps":["trace[873282174] 'agreement among raft nodes before linearized reading' (duration: 539.012364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.318195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:25.778895Z","time spent":"539.289541ms","remote":"127.0.0.1:34576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-09-07T19:13:26.318087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.553457ms","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-09-07T19:13:26.318481Z","caller":"traceutil/trace.go:171","msg":"trace[1610464635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26482; }","duration":"505.975186ms","start":"2026-09-07T19:13:25.812484Z","end":"2026-09-07T19:13:26.318459Z","steps":["trace[1610464635] 'agreement among raft nodes before linearized reading' (duration: 505.446805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.318516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:25.812468Z","time spent":"506.037088ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-07T19:13:26.318547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.131775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-07T19:13:26.318591Z","caller":"traceutil/trace.go:171","msg":"trace[448982133] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26482; }","duration":"662.171136ms","start":"2026-09-07T19:13:25.656404Z","end":"2026-09-07T19:13:26.318575Z","steps":["trace[448982133] 'agreement among raft nodes before linearized reading' (duration: 662.064104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.318629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:25.656327Z","time spent":"662.294459ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-09-07T19:13:26.513175Z","caller":"traceutil/trace.go:171","msg":"trace[11296321] transaction","detail":"{read_only:false; response_revision:26483; number_of_response:1; }","duration":"189.770622ms","start":"2026-09-07T19:13:26.323348Z","end":"2026-09-07T19:13:26.513119Z","steps":["trace[11296321] 'process raft request' (duration: 138.177194ms)","trace[11296321] 'compare' (duration: 51.034085ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:26.51326Z","caller":"traceutil/trace.go:171","msg":"trace[77758841] linearizableReadLoop","detail":"{readStateIndex:27350; appliedIndex:27349; }","duration":"189.856673ms","start":"2026-09-07T19:13:26.323386Z","end":"2026-09-07T19:13:26.513243Z","steps":["trace[77758841] 'read index received' (duration: 138.107412ms)","trace[77758841] 'applied index is now lower than readState.Index' (duration: 51.747131ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:26.513489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.079578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-07T19:13:26.51352Z","caller":"traceutil/trace.go:171","msg":"trace[697360780] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26483; }","duration":"190.15089ms","start":"2026-09-07T19:13:26.32336Z","end":"2026-09-07T19:13:26.513511Z","steps":["trace[697360780] 'agreement among raft nodes before linearized reading' (duration: 189.984536ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:26.513756Z","caller":"traceutil/trace.go:171","msg":"trace[570913717] transaction","detail":"{read_only:false; response_revision:26484; number_of_response:1; }","duration":"166.186326ms","start":"2026-09-07T19:13:26.347556Z","end":"2026-09-07T19:13:26.513742Z","steps":["trace[570913717] 'process raft request' (duration: 166.028712ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:26.514157Z","caller":"traceutil/trace.go:171","msg":"trace[170702274] transaction","detail":"{read_only:false; response_revision:26485; number_of_response:1; }","duration":"165.519001ms","start":"2026-09-07T19:13:26.348618Z","end":"2026-09-07T19:13:26.514137Z","steps":["trace[170702274] 'process raft request' (duration: 165.093962ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:26.514195Z","caller":"traceutil/trace.go:171","msg":"trace[242331677] transaction","detail":"{read_only:false; response_revision:26486; number_of_response:1; }","duration":"163.811645ms","start":"2026-09-07T19:13:26.350342Z","end":"2026-09-07T19:13:26.514154Z","steps":["trace[242331677] 'process raft request' (duration: 163.56084ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:26.691869Z","caller":"traceutil/trace.go:171","msg":"trace[582013483] transaction","detail":"{read_only:false; response_revision:26488; number_of_response:1; }","duration":"168.176729ms","start":"2026-09-07T19:13:26.52367Z","end":"2026-09-07T19:13:26.691846Z","steps":["trace[582013483] 'process raft request' (duration: 103.107853ms)","trace[582013483] 'compare' (duration: 64.923903ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:26.860038Z","caller":"traceutil/trace.go:171","msg":"trace[76751243] linearizableReadLoop","detail":"{readStateIndex:27356; appliedIndex:27355; }","duration":"110.47544ms","start":"2026-09-07T19:13:26.749529Z","end":"2026-09-07T19:13:26.860004Z","steps":["trace[76751243] 'read index received' (duration: 67.397526ms)","trace[76751243] 'applied index is now lower than readState.Index' (duration: 43.076264ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:26.860344Z","caller":"traceutil/trace.go:171","msg":"trace[851296999] transaction","detail":"{read_only:false; response_revision:26489; number_of_response:1; }","duration":"141.937695ms","start":"2026-09-07T19:13:26.718338Z","end":"2026-09-07T19:13:26.860276Z","steps":["trace[851296999] 'process raft request' (duration: 98.574435ms)","trace[851296999] 'compare' (duration: 42.934541ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:26.860469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.669474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:26.860546Z","caller":"traceutil/trace.go:171","msg":"trace[1022241647] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26489; }","duration":"110.783937ms","start":"2026-09-07T19:13:26.749745Z","end":"2026-09-07T19:13:26.860529Z","steps":["trace[1022241647] 'agreement among raft nodes before linearized reading' (duration: 110.626213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.860361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.816227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T19:13:26.860882Z","caller":"traceutil/trace.go:171","msg":"trace[865163796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26489; }","duration":"111.37294ms","start":"2026-09-07T19:13:26.749488Z","end":"2026-09-07T19:13:26.860861Z","steps":["trace[865163796] 'agreement among raft nodes before linearized reading' (duration: 110.703405ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:27.68327Z","caller":"traceutil/trace.go:171","msg":"trace[430290323] transaction","detail":"{read_only:false; response_revision:26494; number_of_response:1; }","duration":"183.974237ms","start":"2026-09-07T19:13:27.499259Z","end":"2026-09-07T19:13:27.683234Z","steps":["trace[430290323] 'process raft request' (duration: 183.698851ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:28.455676Z","caller":"traceutil/trace.go:171","msg":"trace[1923866842] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"131.088423ms","start":"2026-09-07T19:13:28.324543Z","end":"2026-09-07T19:13:28.455631Z","steps":["trace[1923866842] 'process raft request' (duration: 131.040492ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:28.455833Z","caller":"traceutil/trace.go:171","msg":"trace[1084972786] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"164.180643ms","start":"2026-09-07T19:13:28.291619Z","end":"2026-09-07T19:13:28.4558Z","steps":["trace[1084972786] 'process raft request' (duration: 78.400162ms)","trace[1084972786] 'compare' (duration: 85.28261ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:28.455894Z","caller":"traceutil/trace.go:171","msg":"trace[1442857565] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"131.712856ms","start":"2026-09-07T19:13:28.324155Z","end":"2026-09-07T19:13:28.455868Z","steps":["trace[1442857565] 'process raft request' (duration: 131.316498ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:28.730992Z","caller":"traceutil/trace.go:171","msg":"trace[1863566134] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"212.624092ms","start":"2026-09-07T19:13:28.518334Z","end":"2026-09-07T19:13:28.730958Z","steps":["trace[1863566134] 'process raft request' (duration: 145.670026ms)","trace[1863566134] 'compare' (duration: 66.076277ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:28.73114Z","caller":"traceutil/trace.go:171","msg":"trace[961665237] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"212.189741ms","start":"2026-09-07T19:13:28.518883Z","end":"2026-09-07T19:13:28.731073Z","steps":["trace[961665237] 'process raft request' (duration: 211.818183ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:28.731284Z","caller":"traceutil/trace.go:171","msg":"trace[877132703] transaction","detail":"{read_only:false; response_revision:26501; number_of_response:1; }","duration":"211.938856ms","start":"2026-09-07T19:13:28.519331Z","end":"2026-09-07T19:13:28.73127Z","steps":["trace[877132703] 'process raft request' (duration: 211.6513ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:28.731678Z","caller":"traceutil/trace.go:171","msg":"trace[1314068325] linearizableReadLoop","detail":"{readStateIndex:27369; appliedIndex:27365; }","duration":"143.554219ms","start":"2026-09-07T19:13:28.5881Z","end":"2026-09-07T19:13:28.731654Z","steps":["trace[1314068325] 'read index received' (duration: 75.99375ms)","trace[1314068325] 'applied index is now lower than readState.Index' (duration: 67.559179ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:28.731785Z","caller":"traceutil/trace.go:171","msg":"trace[1710914730] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"210.635719ms","start":"2026-09-07T19:13:28.521081Z","end":"2026-09-07T19:13:28.731717Z","steps":["trace[1710914730] 'process raft request' (duration: 210.461675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:28.731965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.836634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-07T19:13:28.732008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.925568ms","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-09-07T19:13:28.73203Z","caller":"traceutil/trace.go:171","msg":"trace[1760891182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26502; }","duration":"135.956827ms","start":"2026-09-07T19:13:28.596055Z","end":"2026-09-07T19:13:28.732012Z","steps":["trace[1760891182] 'agreement among raft nodes before linearized reading' (duration: 135.769183ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:28.732068Z","caller":"traceutil/trace.go:171","msg":"trace[1033441831] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26502; }","duration":"144.054401ms","start":"2026-09-07T19:13:28.587999Z","end":"2026-09-07T19:13:28.732053Z","steps":["trace[1033441831] 'agreement among raft nodes before linearized reading' (duration: 143.815846ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:29.036228Z","caller":"traceutil/trace.go:171","msg":"trace[2146944998] linearizableReadLoop","detail":"{readStateIndex:27372; appliedIndex:27371; }","duration":"134.639949ms","start":"2026-09-07T19:13:28.901564Z","end":"2026-09-07T19:13:29.036204Z","steps":["trace[2146944998] 'read index received' (duration: 134.502416ms)","trace[2146944998] 'applied index is now lower than readState.Index' (duration: 136.363µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:29.036548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.963785ms","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-09-07T19:13:29.036561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.033693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T19:13:29.036604Z","caller":"traceutil/trace.go:171","msg":"trace[1276721000] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26505; }","duration":"135.061477ms","start":"2026-09-07T19:13:28.901527Z","end":"2026-09-07T19:13:29.036588Z","steps":["trace[1276721000] 'agreement among raft nodes before linearized reading' (duration: 134.806282ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:29.036616Z","caller":"traceutil/trace.go:171","msg":"trace[1696085438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26505; }","duration":"105.118604ms","start":"2026-09-07T19:13:28.931483Z","end":"2026-09-07T19:13:29.036601Z","steps":["trace[1696085438] 'agreement among raft nodes before linearized reading' (duration: 104.952591ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:29.036601Z","caller":"traceutil/trace.go:171","msg":"trace[1869819155] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"138.490301ms","start":"2026-09-07T19:13:28.898081Z","end":"2026-09-07T19:13:29.036571Z","steps":["trace[1869819155] 'process raft request' (duration: 137.96925ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:30.578171Z","caller":"traceutil/trace.go:171","msg":"trace[1951259070] transaction","detail":"{read_only:false; response_revision:26512; number_of_response:1; }","duration":"113.818192ms","start":"2026-09-07T19:13:30.464326Z","end":"2026-09-07T19:13:30.578144Z","steps":["trace[1951259070] 'process raft request' (duration: 113.755361ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:30.578225Z","caller":"traceutil/trace.go:171","msg":"trace[764921590] transaction","detail":"{read_only:false; response_revision:26511; number_of_response:1; }","duration":"115.16753ms","start":"2026-09-07T19:13:30.463027Z","end":"2026-09-07T19:13:30.578194Z","steps":["trace[764921590] 'process raft request' (duration: 114.866704ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:30.915994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.621841ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-07T19:13:30.91632Z","caller":"traceutil/trace.go:171","msg":"trace[352518546] linearizableReadLoop","detail":"{readStateIndex:27380; appliedIndex:27379; }","duration":"180.017681ms","start":"2026-09-07T19:13:30.736269Z","end":"2026-09-07T19:13:30.916287Z","steps":["trace[352518546] 'read index received' (duration: 45.901µs)","trace[352518546] 'applied index is now lower than readState.Index' (duration: 179.96884ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:30.916592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.299907ms","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-09-07T19:13:30.916595Z","caller":"traceutil/trace.go:171","msg":"trace[1348020340] transaction","detail":"{read_only:false; response_revision:26513; number_of_response:1; }","duration":"179.672774ms","start":"2026-09-07T19:13:30.736888Z","end":"2026-09-07T19:13:30.91656Z","steps":["trace[1348020340] 'process raft request' (duration: 179.208144ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:30.91667Z","caller":"traceutil/trace.go:171","msg":"trace[110607734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26512; }","duration":"180.3903ms","start":"2026-09-07T19:13:30.73626Z","end":"2026-09-07T19:13:30.916651Z","steps":["trace[110607734] 'agreement among raft nodes before linearized reading' (duration: 180.125465ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.077628Z","caller":"traceutil/trace.go:171","msg":"trace[1549829113] linearizableReadLoop","detail":"{readStateIndex:27383; appliedIndex:27381; }","duration":"161.216099ms","start":"2026-09-07T19:13:30.916389Z","end":"2026-09-07T19:13:31.077605Z","steps":["trace[1549829113] 'read index received' (duration: 126.33481ms)","trace[1549829113] 'applied index is now lower than readState.Index' (duration: 34.879899ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:31.07778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.425374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:31.077811Z","caller":"traceutil/trace.go:171","msg":"trace[1815712968] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26515; }","duration":"340.499486ms","start":"2026-09-07T19:13:30.737302Z","end":"2026-09-07T19:13:31.077801Z","steps":["trace[1815712968] 'agreement among raft nodes before linearized reading' (duration: 340.420234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.07785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:30.737291Z","time spent":"340.550697ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-09-07T19:13:31.07787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.513769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-07T19:13:31.077904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.556608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:31.077966Z","caller":"traceutil/trace.go:171","msg":"trace[224749071] transaction","detail":"{read_only:false; response_revision:26514; number_of_response:1; }","duration":"340.891234ms","start":"2026-09-07T19:13:30.737041Z","end":"2026-09-07T19:13:31.077932Z","steps":["trace[224749071] 'process raft request' (duration: 305.683928ms)","trace[224749071] 'compare' (duration: 34.655314ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:31.078009Z","caller":"traceutil/trace.go:171","msg":"trace[1112223942] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26515; }","duration":"341.703481ms","start":"2026-09-07T19:13:30.736285Z","end":"2026-09-07T19:13:31.077989Z","steps":["trace[1112223942] 'agreement among raft nodes before linearized reading' (duration: 341.562748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.078029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.660588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:31.078066Z","caller":"traceutil/trace.go:171","msg":"trace[1636799197] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26515; }","duration":"181.735099ms","start":"2026-09-07T19:13:30.896322Z","end":"2026-09-07T19:13:31.078057Z","steps":["trace[1636799197] 'agreement among raft nodes before linearized reading' (duration: 181.656478ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.078102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:30.73702Z","time spent":"341.002736ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:13:31.078118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.67732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:31.078168Z","caller":"traceutil/trace.go:171","msg":"trace[1705768328] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26515; }","duration":"341.796662ms","start":"2026-09-07T19:13:30.736356Z","end":"2026-09-07T19:13:31.078153Z","steps":["trace[1705768328] 'agreement among raft nodes before linearized reading' (duration: 341.732251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.078202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:30.736333Z","time spent":"341.860904ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"warn","ts":"2026-09-07T19:13:31.078204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.168077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:31.077944Z","caller":"traceutil/trace.go:171","msg":"trace[665481028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26515; }","duration":"308.608031ms","start":"2026-09-07T19:13:30.769318Z","end":"2026-09-07T19:13:31.077926Z","steps":["trace[665481028] 'agreement among raft nodes before linearized reading' (duration: 308.356575ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.078246Z","caller":"traceutil/trace.go:171","msg":"trace[797162892] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26515; }","duration":"340.234399ms","start":"2026-09-07T19:13:30.738002Z","end":"2026-09-07T19:13:31.078236Z","steps":["trace[797162892] 'agreement among raft nodes before linearized reading' (duration: 340.171078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.078299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:30.737992Z","time spent":"340.29742ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"warn","ts":"2026-09-07T19:13:31.078207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.865514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T19:13:31.078291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.70543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:31.078347Z","caller":"traceutil/trace.go:171","msg":"trace[1446067970] transaction","detail":"{read_only:false; response_revision:26515; number_of_response:1; }","duration":"340.346561ms","start":"2026-09-07T19:13:30.737979Z","end":"2026-09-07T19:13:31.078326Z","steps":["trace[1446067970] 'process raft request' (duration: 339.567065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.078059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:30.736272Z","time spent":"341.773982ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"info","ts":"2026-09-07T19:13:31.078391Z","caller":"traceutil/trace.go:171","msg":"trace[300975420] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26515; }","duration":"342.044708ms","start":"2026-09-07T19:13:30.736285Z","end":"2026-09-07T19:13:31.078329Z","steps":["trace[300975420] 'agreement among raft nodes before linearized reading' (duration: 341.827113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.078483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.828926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-07T19:13:31.078302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:30.769246Z","time spent":"309.01441ms","remote":"127.0.0.1:48328","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-09-07T19:13:31.078519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:30.737966Z","time spent":"340.469054ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:13:31.077864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.160895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-09-07T19:13:31.078554Z","caller":"traceutil/trace.go:171","msg":"trace[900360202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26515; }","duration":"267.918807ms","start":"2026-09-07T19:13:30.810612Z","end":"2026-09-07T19:13:31.078531Z","steps":["trace[900360202] 'agreement among raft nodes before linearized reading' (duration: 267.733393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.078517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:30.736262Z","time spent":"342.237402ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" "} {"level":"info","ts":"2026-09-07T19:13:31.078574Z","caller":"traceutil/trace.go:171","msg":"trace[2110386394] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26515; }","duration":"180.89318ms","start":"2026-09-07T19:13:30.897671Z","end":"2026-09-07T19:13:31.078564Z","steps":["trace[2110386394] 'agreement among raft nodes before linearized reading' (duration: 180.044282ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.078372Z","caller":"traceutil/trace.go:171","msg":"trace[1043156675] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26515; }","duration":"340.820133ms","start":"2026-09-07T19:13:30.737533Z","end":"2026-09-07T19:13:31.078353Z","steps":["trace[1043156675] 'agreement among raft nodes before linearized reading' (duration: 340.671849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.078656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:30.737523Z","time spent":"341.120649ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" "} {"level":"warn","ts":"2026-09-07T19:13:31.079557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.176262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:31.079619Z","caller":"traceutil/trace.go:171","msg":"trace[1682158801] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26515; }","duration":"342.305945ms","start":"2026-09-07T19:13:30.737297Z","end":"2026-09-07T19:13:31.079603Z","steps":["trace[1682158801] 'agreement among raft nodes before linearized reading' (duration: 340.389933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.079666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:30.737284Z","time spent":"342.370006ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"info","ts":"2026-09-07T19:13:31.103364Z","caller":"traceutil/trace.go:171","msg":"trace[1735228028] transaction","detail":"{read_only:false; response_revision:26516; number_of_response:1; }","duration":"182.417194ms","start":"2026-09-07T19:13:30.920926Z","end":"2026-09-07T19:13:31.103343Z","steps":["trace[1735228028] 'process raft request' (duration: 182.23765ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.103606Z","caller":"traceutil/trace.go:171","msg":"trace[746112293] transaction","detail":"{read_only:false; response_revision:26517; number_of_response:1; }","duration":"118.88598ms","start":"2026-09-07T19:13:30.98468Z","end":"2026-09-07T19:13:31.103566Z","steps":["trace[746112293] 'process raft request' (duration: 118.619344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.772694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.511954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:31.772936Z","caller":"traceutil/trace.go:171","msg":"trace[2130508401] linearizableReadLoop","detail":"{readStateIndex:27391; appliedIndex:27389; }","duration":"118.613244ms","start":"2026-09-07T19:13:31.654295Z","end":"2026-09-07T19:13:31.772908Z","steps":["trace[2130508401] 'read index received' (duration: 20.621252ms)","trace[2130508401] 'applied index is now lower than readState.Index' (duration: 97.990822ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:31.773048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.750617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:31.773087Z","caller":"traceutil/trace.go:171","msg":"trace[1252041021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26523; }","duration":"118.829888ms","start":"2026-09-07T19:13:31.654244Z","end":"2026-09-07T19:13:31.773074Z","steps":["trace[1252041021] 'agreement among raft nodes before linearized reading' (duration: 118.730356ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.773064Z","caller":"traceutil/trace.go:171","msg":"trace[1732266416] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"225.205742ms","start":"2026-09-07T19:13:31.547835Z","end":"2026-09-07T19:13:31.77304Z","steps":["trace[1732266416] 'process raft request' (duration: 224.982577ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.773075Z","caller":"traceutil/trace.go:171","msg":"trace[1019263482] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"488.060181ms","start":"2026-09-07T19:13:31.284983Z","end":"2026-09-07T19:13:31.773043Z","steps":["trace[1019263482] 'process raft request' (duration: 285.059166ms)","trace[1019263482] 'compare' (duration: 202.333801ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:31.77326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:31.284937Z","time spent":"488.238905ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:13:32.820739Z","caller":"traceutil/trace.go:171","msg":"trace[747653852] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"234.514911ms","start":"2026-09-07T19:13:32.586196Z","end":"2026-09-07T19:13:32.820711Z","steps":["trace[747653852] 'process raft request' (duration: 234.360298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.47815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.622102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:33.478249Z","caller":"traceutil/trace.go:171","msg":"trace[775119524] linearizableReadLoop","detail":"{readStateIndex:27398; appliedIndex:27397; }","duration":"320.880805ms","start":"2026-09-07T19:13:33.157352Z","end":"2026-09-07T19:13:33.478233Z","steps":["trace[775119524] 'read index received' (duration: 162.970186ms)","trace[775119524] 'applied index is now lower than readState.Index' (duration: 157.909399ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:33.478572Z","caller":"traceutil/trace.go:171","msg":"trace[891488977] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"324.921202ms","start":"2026-09-07T19:13:33.153637Z","end":"2026-09-07T19:13:33.478558Z","steps":["trace[891488977] 'process raft request' (duration: 166.802829ms)","trace[891488977] 'compare' (duration: 157.478079ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:33.478663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:33.153606Z","time spent":"325.020813ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:13:33.478648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.579068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-07T19:13:33.478725Z","caller":"traceutil/trace.go:171","msg":"trace[1674901332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26530; }","duration":"320.67058ms","start":"2026-09-07T19:13:33.158033Z","end":"2026-09-07T19:13:33.478703Z","steps":["trace[1674901332] 'agreement among raft nodes before linearized reading' (duration: 320.441025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.478745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.743637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-07T19:13:33.478776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:33.157992Z","time spent":"320.772302ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-07T19:13:33.478825Z","caller":"traceutil/trace.go:171","msg":"trace[361489483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26530; }","duration":"310.86261ms","start":"2026-09-07T19:13:33.167943Z","end":"2026-09-07T19:13:33.478806Z","steps":["trace[361489483] 'agreement among raft nodes before linearized reading' (duration: 310.584124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.478904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:33.167929Z","time spent":"310.961102ms","remote":"127.0.0.1:48328","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-09-07T19:13:33.478907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.542859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-07T19:13:33.479007Z","caller":"traceutil/trace.go:171","msg":"trace[1263529194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26530; }","duration":"321.665032ms","start":"2026-09-07T19:13:33.157316Z","end":"2026-09-07T19:13:33.478981Z","steps":["trace[1263529194] 'agreement among raft nodes before linearized reading' (duration: 321.314434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.479022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.132844ms","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-09-07T19:13:33.479065Z","caller":"traceutil/trace.go:171","msg":"trace[988388708] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26530; }","duration":"242.218946ms","start":"2026-09-07T19:13:33.236835Z","end":"2026-09-07T19:13:33.479054Z","steps":["trace[988388708] 'agreement among raft nodes before linearized reading' (duration: 241.744946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.479071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:33.157303Z","time spent":"321.753403ms","remote":"127.0.0.1:48328","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-09-07T19:13:33.479089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.711559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-09-07T19:13:33.479144Z","caller":"traceutil/trace.go:171","msg":"trace[1738406283] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26530; }","duration":"318.80307ms","start":"2026-09-07T19:13:33.160327Z","end":"2026-09-07T19:13:33.47913Z","steps":["trace[1738406283] 'agreement among raft nodes before linearized reading' (duration: 318.196397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.47918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:33.160307Z","time spent":"318.865292ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5103,"request content":"key:\"/registry/jobs/openstack/magnum-db-sync\" "} {"level":"warn","ts":"2026-09-07T19:13:33.47953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.651948ms","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-09-07T19:13:33.479582Z","caller":"traceutil/trace.go:171","msg":"trace[226466809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26530; }","duration":"231.484116ms","start":"2026-09-07T19:13:33.248085Z","end":"2026-09-07T19:13:33.479569Z","steps":["trace[226466809] 'agreement among raft nodes before linearized reading' (duration: 230.412533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.766352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.424661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:33.766649Z","caller":"traceutil/trace.go:171","msg":"trace[469708784] transaction","detail":"{read_only:false; response_revision:26532; number_of_response:1; }","duration":"282.4528ms","start":"2026-09-07T19:13:33.484172Z","end":"2026-09-07T19:13:33.766624Z","steps":["trace[469708784] 'process raft request' (duration: 282.315717ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:33.76667Z","caller":"traceutil/trace.go:171","msg":"trace[118177418] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"282.44514ms","start":"2026-09-07T19:13:33.484118Z","end":"2026-09-07T19:13:33.766563Z","steps":["trace[118177418] 'process raft request' (duration: 177.701252ms)","trace[118177418] 'compare' (duration: 104.288038ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:33.766817Z","caller":"traceutil/trace.go:171","msg":"trace[438397230] transaction","detail":"{read_only:false; response_revision:26533; number_of_response:1; }","duration":"282.617334ms","start":"2026-09-07T19:13:33.484188Z","end":"2026-09-07T19:13:33.766806Z","steps":["trace[438397230] 'process raft request' (duration: 282.387769ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:33.766809Z","caller":"traceutil/trace.go:171","msg":"trace[1468264754] linearizableReadLoop","detail":"{readStateIndex:27402; appliedIndex:27398; }","duration":"113.174589ms","start":"2026-09-07T19:13:33.653616Z","end":"2026-09-07T19:13:33.766791Z","steps":["trace[1468264754] 'read index received' (duration: 8.203836ms)","trace[1468264754] 'applied index is now lower than readState.Index' (duration: 104.969473ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:33.766968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.338783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:33.767008Z","caller":"traceutil/trace.go:171","msg":"trace[1947811539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26534; }","duration":"113.434984ms","start":"2026-09-07T19:13:33.653562Z","end":"2026-09-07T19:13:33.766997Z","steps":["trace[1947811539] 'agreement among raft nodes before linearized reading' (duration: 113.292431ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:33.766984Z","caller":"traceutil/trace.go:171","msg":"trace[1908239226] transaction","detail":"{read_only:false; response_revision:26534; number_of_response:1; }","duration":"282.693445ms","start":"2026-09-07T19:13:33.484255Z","end":"2026-09-07T19:13:33.766948Z","steps":["trace[1908239226] 'process raft request' (duration: 282.46796ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:33.958761Z","caller":"traceutil/trace.go:171","msg":"trace[792526908] transaction","detail":"{read_only:false; response_revision:26535; number_of_response:1; }","duration":"180.272947ms","start":"2026-09-07T19:13:33.778458Z","end":"2026-09-07T19:13:33.958731Z","steps":["trace[792526908] 'process raft request' (duration: 123.037319ms)","trace[792526908] 'compare' (duration: 56.907511ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:33.958835Z","caller":"traceutil/trace.go:171","msg":"trace[381033066] transaction","detail":"{read_only:false; response_revision:26536; number_of_response:1; }","duration":"179.576753ms","start":"2026-09-07T19:13:33.779226Z","end":"2026-09-07T19:13:33.958803Z","steps":["trace[381033066] 'process raft request' (duration: 179.46935ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:34.753307Z","caller":"traceutil/trace.go:171","msg":"trace[1196573295] transaction","detail":"{read_only:false; response_revision:26539; number_of_response:1; }","duration":"127.094077ms","start":"2026-09-07T19:13:34.626115Z","end":"2026-09-07T19:13:34.753209Z","steps":["trace[1196573295] 'process raft request' (duration: 44.193368ms)","trace[1196573295] 'compare' (duration: 82.732015ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:35.25676Z","caller":"traceutil/trace.go:171","msg":"trace[1489469568] transaction","detail":"{read_only:false; response_revision:26542; number_of_response:1; }","duration":"129.264383ms","start":"2026-09-07T19:13:35.127477Z","end":"2026-09-07T19:13:35.256741Z","steps":["trace[1489469568] 'process raft request' (duration: 129.145651ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:35.256974Z","caller":"traceutil/trace.go:171","msg":"trace[1601567879] linearizableReadLoop","detail":"{readStateIndex:27410; appliedIndex:27410; }","duration":"102.244274ms","start":"2026-09-07T19:13:35.154721Z","end":"2026-09-07T19:13:35.256965Z","steps":["trace[1601567879] 'read index received' (duration: 102.239924ms)","trace[1601567879] 'applied index is now lower than readState.Index' (duration: 3.92µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:35.257046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.308765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:35.25708Z","caller":"traceutil/trace.go:171","msg":"trace[150752175] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26542; }","duration":"102.391687ms","start":"2026-09-07T19:13:35.154672Z","end":"2026-09-07T19:13:35.257064Z","steps":["trace[150752175] 'agreement among raft nodes before linearized reading' (duration: 102.333326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:35.292555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.605806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T19:13:35.292585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.120619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:35.292615Z","caller":"traceutil/trace.go:171","msg":"trace[382876827] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26543; }","duration":"133.687529ms","start":"2026-09-07T19:13:35.158913Z","end":"2026-09-07T19:13:35.2926Z","steps":["trace[382876827] 'agreement among raft nodes before linearized reading' (duration: 133.564535ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:35.292646Z","caller":"traceutil/trace.go:171","msg":"trace[1139118510] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26543; }","duration":"135.194831ms","start":"2026-09-07T19:13:35.157434Z","end":"2026-09-07T19:13:35.292629Z","steps":["trace[1139118510] 'agreement among raft nodes before linearized reading' (duration: 135.092568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:35.292684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.643591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-09-07T19:13:35.292719Z","caller":"traceutil/trace.go:171","msg":"trace[851791934] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26543; }","duration":"135.686612ms","start":"2026-09-07T19:13:35.15702Z","end":"2026-09-07T19:13:35.292707Z","steps":["trace[851791934] 'agreement among raft nodes before linearized reading' (duration: 135.56834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:35.292555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.403308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:35.29277Z","caller":"traceutil/trace.go:171","msg":"trace[1338787779] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26543; }","duration":"137.664984ms","start":"2026-09-07T19:13:35.155093Z","end":"2026-09-07T19:13:35.292758Z","steps":["trace[1338787779] 'agreement among raft nodes before linearized reading' (duration: 137.379837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:35.292737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.892715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T19:13:35.292777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.865497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:35.292837Z","caller":"traceutil/trace.go:171","msg":"trace[222568503] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26543; }","duration":"135.043168ms","start":"2026-09-07T19:13:35.157768Z","end":"2026-09-07T19:13:35.292811Z","steps":["trace[222568503] 'agreement among raft nodes before linearized reading' (duration: 134.814162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:35.292853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.32675ms","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":"info","ts":"2026-09-07T19:13:35.29288Z","caller":"traceutil/trace.go:171","msg":"trace[1245183355] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26543; }","duration":"136.99991ms","start":"2026-09-07T19:13:35.155851Z","end":"2026-09-07T19:13:35.292851Z","steps":["trace[1245183355] 'agreement among raft nodes before linearized reading' (duration: 136.799466ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:35.292898Z","caller":"traceutil/trace.go:171","msg":"trace[77705995] transaction","detail":"{read_only:false; response_revision:26543; number_of_response:1; }","duration":"136.379737ms","start":"2026-09-07T19:13:35.156511Z","end":"2026-09-07T19:13:35.29289Z","steps":["trace[77705995] 'process raft request' (duration: 135.781693ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:35.292913Z","caller":"traceutil/trace.go:171","msg":"trace[1531709000] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:26543; }","duration":"118.434142ms","start":"2026-09-07T19:13:35.174459Z","end":"2026-09-07T19:13:35.292893Z","steps":["trace[1531709000] 'agreement among raft nodes before linearized reading' (duration: 118.263658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:35.653342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.260585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:35.653553Z","caller":"traceutil/trace.go:171","msg":"trace[1855087537] transaction","detail":"{read_only:false; response_revision:26545; number_of_response:1; }","duration":"166.587764ms","start":"2026-09-07T19:13:35.486922Z","end":"2026-09-07T19:13:35.65351Z","steps":["trace[1855087537] 'process raft request' (duration: 42.057183ms)","trace[1855087537] 'compare' (duration: 124.01804ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:35.888191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.177034ms","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-09-07T19:13:35.888292Z","caller":"traceutil/trace.go:171","msg":"trace[591236275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26545; }","duration":"117.335008ms","start":"2026-09-07T19:13:35.770926Z","end":"2026-09-07T19:13:35.888261Z","steps":["trace[591236275] 'range keys from in-memory index tree' (duration: 116.96528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:35.888279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.246215ms","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-09-07T19:13:35.888366Z","caller":"traceutil/trace.go:171","msg":"trace[1010889029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26545; }","duration":"117.402338ms","start":"2026-09-07T19:13:35.770943Z","end":"2026-09-07T19:13:35.888345Z","steps":["trace[1010889029] 'range keys from in-memory index tree' (duration: 117.023651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:35.888353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.398749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-07T19:13:35.888454Z","caller":"traceutil/trace.go:171","msg":"trace[1759525791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26545; }","duration":"117.567902ms","start":"2026-09-07T19:13:35.770869Z","end":"2026-09-07T19:13:35.888436Z","steps":["trace[1759525791] 'range keys from in-memory index tree' (duration: 117.153793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:35.888469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.49863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T19:13:35.888539Z","caller":"traceutil/trace.go:171","msg":"trace[1698508759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26545; }","duration":"117.599073ms","start":"2026-09-07T19:13:35.770924Z","end":"2026-09-07T19:13:35.888523Z","steps":["trace[1698508759] 'range keys from in-memory index tree' (duration: 116.98992ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:36.091404Z","caller":"traceutil/trace.go:171","msg":"trace[1291217854] transaction","detail":"{read_only:false; response_revision:26549; number_of_response:1; }","duration":"197.868635ms","start":"2026-09-07T19:13:35.893489Z","end":"2026-09-07T19:13:36.091358Z","steps":["trace[1291217854] 'process raft request' (duration: 197.824274ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:36.091441Z","caller":"traceutil/trace.go:171","msg":"trace[1695899367] transaction","detail":"{read_only:false; response_revision:26547; number_of_response:1; }","duration":"198.158722ms","start":"2026-09-07T19:13:35.893204Z","end":"2026-09-07T19:13:36.091363Z","steps":["trace[1695899367] 'process raft request' (duration: 171.07765ms)","trace[1695899367] 'compare' (duration: 26.872917ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:36.091585Z","caller":"traceutil/trace.go:171","msg":"trace[2044343030] transaction","detail":"{read_only:false; response_revision:26548; number_of_response:1; }","duration":"198.240263ms","start":"2026-09-07T19:13:35.893333Z","end":"2026-09-07T19:13:36.091573Z","steps":["trace[2044343030] 'process raft request' (duration: 197.943957ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:36.094017Z","caller":"traceutil/trace.go:171","msg":"trace[1341875426] transaction","detail":"{read_only:false; response_revision:26550; number_of_response:1; }","duration":"130.345557ms","start":"2026-09-07T19:13:35.963643Z","end":"2026-09-07T19:13:36.093988Z","steps":["trace[1341875426] 'process raft request' (duration: 130.144603ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:36.094213Z","caller":"traceutil/trace.go:171","msg":"trace[374307839] transaction","detail":"{read_only:false; response_revision:26551; number_of_response:1; }","duration":"130.47826ms","start":"2026-09-07T19:13:35.963706Z","end":"2026-09-07T19:13:36.094184Z","steps":["trace[374307839] 'process raft request' (duration: 130.226415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:36.337078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.617723ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:36.337219Z","caller":"traceutil/trace.go:171","msg":"trace[1350584557] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26551; }","duration":"124.799697ms","start":"2026-09-07T19:13:36.212397Z","end":"2026-09-07T19:13:36.337196Z","steps":["trace[1350584557] 'range keys from in-memory index tree' (duration: 124.595593ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:36.930465Z","caller":"traceutil/trace.go:171","msg":"trace[1573487762] linearizableReadLoop","detail":"{readStateIndex:27422; appliedIndex:27421; }","duration":"140.530024ms","start":"2026-09-07T19:13:36.789908Z","end":"2026-09-07T19:13:36.930438Z","steps":["trace[1573487762] 'read index received' (duration: 140.33802ms)","trace[1573487762] 'applied index is now lower than readState.Index' (duration: 190.284µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:36.930581Z","caller":"traceutil/trace.go:171","msg":"trace[601413315] transaction","detail":"{read_only:false; response_revision:26553; number_of_response:1; }","duration":"171.821926ms","start":"2026-09-07T19:13:36.758734Z","end":"2026-09-07T19:13:36.930556Z","steps":["trace[601413315] 'process raft request' (duration: 171.431787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:36.930714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.7755ms","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-09-07T19:13:36.930753Z","caller":"traceutil/trace.go:171","msg":"trace[964974099] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26553; }","duration":"140.894473ms","start":"2026-09-07T19:13:36.789844Z","end":"2026-09-07T19:13:36.930739Z","steps":["trace[964974099] 'agreement among raft nodes before linearized reading' (duration: 140.712719ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:37.302174Z","caller":"traceutil/trace.go:171","msg":"trace[533974139] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"445.10641ms","start":"2026-09-07T19:13:36.857037Z","end":"2026-09-07T19:13:37.302144Z","steps":["trace[533974139] 'process raft request' (duration: 445.026998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:37.302394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:36.857023Z","time spent":"445.240173ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:13:37.302994Z","caller":"traceutil/trace.go:171","msg":"trace[90927008] transaction","detail":"{read_only:false; response_revision:26554; number_of_response:1; }","duration":"466.483878ms","start":"2026-09-07T19:13:36.836491Z","end":"2026-09-07T19:13:37.302975Z","steps":["trace[90927008] 'process raft request' (duration: 465.389705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:37.303109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:36.83647Z","time spent":"466.59344ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7681,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:13:37.460766Z","caller":"traceutil/trace.go:171","msg":"trace[187122481] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"451.536277ms","start":"2026-09-07T19:13:37.009202Z","end":"2026-09-07T19:13:37.460738Z","steps":["trace[187122481] 'process raft request' (duration: 364.557031ms)","trace[187122481] 'compare' (duration: 86.781012ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:37.461Z","caller":"traceutil/trace.go:171","msg":"trace[1180483500] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"198.219563ms","start":"2026-09-07T19:13:37.262754Z","end":"2026-09-07T19:13:37.460973Z","steps":["trace[1180483500] 'process raft request' (duration: 197.943237ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:37.461028Z","caller":"traceutil/trace.go:171","msg":"trace[2023390647] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"155.006016ms","start":"2026-09-07T19:13:37.306005Z","end":"2026-09-07T19:13:37.461011Z","steps":["trace[2023390647] 'process raft request' (duration: 154.938135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:37.461112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:37.009166Z","time spent":"451.823415ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:13:37.461128Z","caller":"traceutil/trace.go:171","msg":"trace[1614799971] linearizableReadLoop","detail":"{readStateIndex:27425; appliedIndex:27422; }","duration":"330.719465ms","start":"2026-09-07T19:13:37.1303Z","end":"2026-09-07T19:13:37.46102Z","steps":["trace[1614799971] 'read index received' (duration: 171.471849ms)","trace[1614799971] 'applied index is now lower than readState.Index' (duration: 159.238056ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:37.461344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.020802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:37.461409Z","caller":"traceutil/trace.go:171","msg":"trace[326476356] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26558; }","duration":"331.143155ms","start":"2026-09-07T19:13:37.130252Z","end":"2026-09-07T19:13:37.461395Z","steps":["trace[326476356] 'agreement among raft nodes before linearized reading' (duration: 331.029893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:37.461451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:37.13023Z","time spent":"331.209137ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-09-07T19:13:37.493452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.993562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T19:13:37.49346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.007702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:37.49351Z","caller":"traceutil/trace.go:171","msg":"trace[922105285] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26559; }","duration":"187.097925ms","start":"2026-09-07T19:13:37.306399Z","end":"2026-09-07T19:13:37.493497Z","steps":["trace[922105285] 'agreement among raft nodes before linearized reading' (duration: 186.995282ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:37.493535Z","caller":"traceutil/trace.go:171","msg":"trace[1430385107] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26559; }","duration":"187.104014ms","start":"2026-09-07T19:13:37.306409Z","end":"2026-09-07T19:13:37.493513Z","steps":["trace[1430385107] 'agreement among raft nodes before linearized reading' (duration: 186.997392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:37.493533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.055745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T19:13:37.493572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.192287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T19:13:37.493605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.030508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:37.493624Z","caller":"traceutil/trace.go:171","msg":"trace[1983462606] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26559; }","duration":"229.193738ms","start":"2026-09-07T19:13:37.264405Z","end":"2026-09-07T19:13:37.493599Z","steps":["trace[1983462606] 'agreement among raft nodes before linearized reading' (duration: 229.028764ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:37.493646Z","caller":"traceutil/trace.go:171","msg":"trace[697387233] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26559; }","duration":"187.289958ms","start":"2026-09-07T19:13:37.306337Z","end":"2026-09-07T19:13:37.493627Z","steps":["trace[697387233] 'agreement among raft nodes before linearized reading' (duration: 187.161936ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:37.493661Z","caller":"traceutil/trace.go:171","msg":"trace[207527438] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26559; }","duration":"363.139911ms","start":"2026-09-07T19:13:37.130506Z","end":"2026-09-07T19:13:37.493646Z","steps":["trace[207527438] 'agreement among raft nodes before linearized reading' (duration: 363.034069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:37.493668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.161195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:37.493695Z","caller":"traceutil/trace.go:171","msg":"trace[412443117] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26559; }","duration":"187.208687ms","start":"2026-09-07T19:13:37.306479Z","end":"2026-09-07T19:13:37.493687Z","steps":["trace[412443117] 'agreement among raft nodes before linearized reading' (duration: 187.168026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:37.493703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:37.130467Z","time spent":"363.222583ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"warn","ts":"2026-09-07T19:13:37.493697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.078407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19231"} {"level":"info","ts":"2026-09-07T19:13:37.493747Z","caller":"traceutil/trace.go:171","msg":"trace[1574363145] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26559; }","duration":"182.156098ms","start":"2026-09-07T19:13:37.311574Z","end":"2026-09-07T19:13:37.49373Z","steps":["trace[1574363145] 'agreement among raft nodes before linearized reading' (duration: 181.992664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:37.493779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.319978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:37.493801Z","caller":"traceutil/trace.go:171","msg":"trace[111093175] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26559; }","duration":"187.35717ms","start":"2026-09-07T19:13:37.306436Z","end":"2026-09-07T19:13:37.493793Z","steps":["trace[111093175] 'agreement among raft nodes before linearized reading' (duration: 187.324869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:37.493904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.738858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-09-07T19:13:37.493962Z","caller":"traceutil/trace.go:171","msg":"trace[1568714521] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26559; }","duration":"187.82917ms","start":"2026-09-07T19:13:37.306117Z","end":"2026-09-07T19:13:37.493946Z","steps":["trace[1568714521] 'agreement among raft nodes before linearized reading' (duration: 187.626926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:39.150187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.41599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-07T19:13:39.150278Z","caller":"traceutil/trace.go:171","msg":"trace[871271971] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:26570; }","duration":"159.576023ms","start":"2026-09-07T19:13:38.99068Z","end":"2026-09-07T19:13:39.150256Z","steps":["trace[871271971] 'count revisions from in-memory index tree' (duration: 159.295787ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:39.414304Z","caller":"traceutil/trace.go:171","msg":"trace[552631863] transaction","detail":"{read_only:false; response_revision:26571; number_of_response:1; }","duration":"104.980043ms","start":"2026-09-07T19:13:39.309266Z","end":"2026-09-07T19:13:39.414246Z","steps":["trace[552631863] 'process raft request' (duration: 104.723057ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:39.534424Z","caller":"traceutil/trace.go:171","msg":"trace[420551387] linearizableReadLoop","detail":"{readStateIndex:27441; appliedIndex:27440; }","duration":"111.232677ms","start":"2026-09-07T19:13:39.423164Z","end":"2026-09-07T19:13:39.534396Z","steps":["trace[420551387] 'read index received' (duration: 32.928437ms)","trace[420551387] 'applied index is now lower than readState.Index' (duration: 78.30262ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:39.534513Z","caller":"traceutil/trace.go:171","msg":"trace[1438273573] transaction","detail":"{read_only:false; response_revision:26572; number_of_response:1; }","duration":"113.011145ms","start":"2026-09-07T19:13:39.421446Z","end":"2026-09-07T19:13:39.534457Z","steps":["trace[1438273573] 'process raft request' (duration: 34.691575ms)","trace[1438273573] 'compare' (duration: 78.031214ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:39.534652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.470532ms","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-09-07T19:13:39.534699Z","caller":"traceutil/trace.go:171","msg":"trace[1081007597] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26573; }","duration":"111.555314ms","start":"2026-09-07T19:13:39.423135Z","end":"2026-09-07T19:13:39.53469Z","steps":["trace[1081007597] 'agreement among raft nodes before linearized reading' (duration: 111.397581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:39.577886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.446692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:39.578022Z","caller":"traceutil/trace.go:171","msg":"trace[1133742073] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26575; }","duration":"111.643836ms","start":"2026-09-07T19:13:39.466349Z","end":"2026-09-07T19:13:39.577993Z","steps":["trace[1133742073] 'agreement among raft nodes before linearized reading' (duration: 111.463792ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:39.578079Z","caller":"traceutil/trace.go:171","msg":"trace[113502649] transaction","detail":"{read_only:false; response_revision:26575; number_of_response:1; }","duration":"110.940439ms","start":"2026-09-07T19:13:39.467126Z","end":"2026-09-07T19:13:39.578066Z","steps":["trace[113502649] 'process raft request' (duration: 110.315886ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:39.57799Z","caller":"traceutil/trace.go:171","msg":"trace[47409932] transaction","detail":"{read_only:false; response_revision:26574; number_of_response:1; }","duration":"111.886791ms","start":"2026-09-07T19:13:39.466063Z","end":"2026-09-07T19:13:39.57795Z","steps":["trace[47409932] 'process raft request' (duration: 111.086304ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:40.349517Z","caller":"traceutil/trace.go:171","msg":"trace[950575977] transaction","detail":"{read_only:false; response_revision:26580; number_of_response:1; }","duration":"140.830742ms","start":"2026-09-07T19:13:40.208649Z","end":"2026-09-07T19:13:40.34948Z","steps":["trace[950575977] 'process raft request' (duration: 140.506526ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:40.349562Z","caller":"traceutil/trace.go:171","msg":"trace[1025676156] transaction","detail":"{read_only:false; response_revision:26582; number_of_response:1; }","duration":"140.001143ms","start":"2026-09-07T19:13:40.209528Z","end":"2026-09-07T19:13:40.349529Z","steps":["trace[1025676156] 'process raft request' (duration: 139.897491ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:40.349568Z","caller":"traceutil/trace.go:171","msg":"trace[1415946699] transaction","detail":"{read_only:false; response_revision:26581; number_of_response:1; }","duration":"140.70364ms","start":"2026-09-07T19:13:40.208827Z","end":"2026-09-07T19:13:40.34953Z","steps":["trace[1415946699] 'process raft request' (duration: 140.497645ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:15:01.724486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24787} {"level":"info","ts":"2026-09-07T19:15:01.891597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24787,"took":"162.890505ms","hash":118822568,"current-db-size-bytes":58126336,"current-db-size":"58 MB","current-db-size-in-use-bytes":33370112,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-07T19:15:01.891759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":118822568,"revision":24787,"compact-revision":21775} {"level":"info","ts":"2026-09-07T19:19:15.343794Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30ad0c2b6ecdd25e","local-member-applied-index":30006,"local-member-snapshot-index":20005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T19:19:15.350904Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30006} {"level":"info","ts":"2026-09-07T19:19:15.35104Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25006}