{"level":"warn","ts":"2026-09-07T18:19:12.878995Z","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:19:12.879097Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.223: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.223:2380","--initial-cluster=instance=https://162.253.55.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.223: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:19:12.879172Z","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:19:12.879181Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.223:2380"]} {"level":"info","ts":"2026-09-07T18:19:12.879205Z","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:19:12.879989Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"]} {"level":"info","ts":"2026-09-07T18:19:12.880087Z","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.223:2380"],"listen-peer-urls":["https://162.253.55.223:2380"],"advertise-client-urls":["https://162.253.55.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.223: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:19:12.883705Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.334979ms"} {"level":"info","ts":"2026-09-07T18:19:12.89552Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0f50f8a6c5f7d06","cluster-id":"62f129f77cf8e487"} {"level":"info","ts":"2026-09-07T18:19:12.8957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T18:19:12.895754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became follower at term 0"} {"level":"info","ts":"2026-09-07T18:19:12.895796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0f50f8a6c5f7d06 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T18:19:12.895819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became follower at term 1"} {"level":"info","ts":"2026-09-07T18:19:12.895888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=(11598193502560681222)"} {"level":"warn","ts":"2026-09-07T18:19:12.904071Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T18:19:12.906149Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T18:19:12.907923Z","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:19:12.911158Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0f50f8a6c5f7d06","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T18:19:12.911442Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0f50f8a6c5f7d06","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T18:19:12.911522Z","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:19:12.911717Z","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:19:12.911736Z","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:19:12.912814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=(11598193502560681222)"} {"level":"info","ts":"2026-09-07T18:19:12.913052Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62f129f77cf8e487","local-member-id":"a0f50f8a6c5f7d06","added-peer-id":"a0f50f8a6c5f7d06","added-peer-peer-urls":["https://162.253.55.223:2380"]} {"level":"info","ts":"2026-09-07T18:19:12.920857Z","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:19:12.921013Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.223:2380"} {"level":"info","ts":"2026-09-07T18:19:12.921062Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.223:2380"} {"level":"info","ts":"2026-09-07T18:19:12.921245Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0f50f8a6c5f7d06","initial-advertise-peer-urls":["https://162.253.55.223:2380"],"listen-peer-urls":["https://162.253.55.223:2380"],"advertise-client-urls":["https://162.253.55.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T18:19:12.921342Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T18:19:12.996357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T18:19:12.996439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T18:19:12.996468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 received MsgPreVoteResp from a0f50f8a6c5f7d06 at term 1"} {"level":"info","ts":"2026-09-07T18:19:12.996494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became candidate at term 2"} {"level":"info","ts":"2026-09-07T18:19:12.99651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 received MsgVoteResp from a0f50f8a6c5f7d06 at term 2"} {"level":"info","ts":"2026-09-07T18:19:12.996531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became leader at term 2"} {"level":"info","ts":"2026-09-07T18:19:12.996549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0f50f8a6c5f7d06 elected leader a0f50f8a6c5f7d06 at term 2"} {"level":"info","ts":"2026-09-07T18:19:12.997855Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:19:12.998011Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0f50f8a6c5f7d06","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.223:2379]}","request-path":"/0/members/a0f50f8a6c5f7d06/attributes","cluster-id":"62f129f77cf8e487","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T18:19:12.998069Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:19:12.998016Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:19:12.998655Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T18:19:12.998699Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T18:19:12.998961Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62f129f77cf8e487","local-member-id":"a0f50f8a6c5f7d06","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:19:12.999069Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:19:12.999104Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:19:13.001798Z","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:19:13.002716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.223:2379"} {"level":"info","ts":"2026-09-07T18:19:25.547927Z","caller":"traceutil/trace.go:171","msg":"trace[1040580618] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"163.896265ms","start":"2026-09-07T18:19:25.383993Z","end":"2026-09-07T18:19:25.54789Z","steps":["trace[1040580618] 'read index received' (duration: 163.666ms)","trace[1040580618] 'applied index is now lower than readState.Index' (duration: 228.425µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:19:25.548001Z","caller":"traceutil/trace.go:171","msg":"trace[1807137523] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"290.789225ms","start":"2026-09-07T18:19:25.257168Z","end":"2026-09-07T18:19:25.547957Z","steps":["trace[1807137523] 'process raft request' (duration: 290.504029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:19:25.548227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.192832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-07T18:19:25.548365Z","caller":"traceutil/trace.go:171","msg":"trace[340750915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"164.402146ms","start":"2026-09-07T18:19:25.383948Z","end":"2026-09-07T18:19:25.54835Z","steps":["trace[340750915] 'agreement among raft nodes before linearized reading' (duration: 164.14484ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:19:25.730919Z","caller":"traceutil/trace.go:171","msg":"trace[1162479161] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"177.189843ms","start":"2026-09-07T18:19:25.553675Z","end":"2026-09-07T18:19:25.730865Z","steps":["trace[1162479161] 'process raft request' (duration: 77.898623ms)","trace[1162479161] 'compare' (duration: 99.106087ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:19:57.08027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.063976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:19:57.080398Z","caller":"traceutil/trace.go:171","msg":"trace[142811160] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"194.513594ms","start":"2026-09-07T18:19:56.885865Z","end":"2026-09-07T18:19:57.080379Z","steps":["trace[142811160] 'process raft request' (duration: 19.665552ms)","trace[142811160] 'compare' (duration: 173.929223ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:20:03.472279Z","caller":"traceutil/trace.go:171","msg":"trace[1331489797] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"170.613374ms","start":"2026-09-07T18:20:03.30164Z","end":"2026-09-07T18:20:03.472253Z","steps":["trace[1331489797] 'process raft request' (duration: 169.95947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:20:03.707569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.026847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:20:03.707883Z","caller":"traceutil/trace.go:171","msg":"trace[1735580660] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"157.953599ms","start":"2026-09-07T18:20:03.5499Z","end":"2026-09-07T18:20:03.707854Z","steps":["trace[1735580660] 'process raft request' (duration: 24.516214ms)","trace[1735580660] 'compare' (duration: 132.690469ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:20:04.189646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.809906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:20:04.189755Z","caller":"traceutil/trace.go:171","msg":"trace[2144740371] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"334.370285ms","start":"2026-09-07T18:20:03.855367Z","end":"2026-09-07T18:20:04.189737Z","steps":["trace[2144740371] 'read index received' (duration: 8.313944ms)","trace[2144740371] 'applied index is now lower than readState.Index' (duration: 326.054861ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:20:04.189844Z","caller":"traceutil/trace.go:171","msg":"trace[1462179084] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"427.870783ms","start":"2026-09-07T18:20:03.761938Z","end":"2026-09-07T18:20:04.189809Z","steps":["trace[1462179084] 'process raft request' (duration: 101.817793ms)","trace[1462179084] 'compare' (duration: 325.623681ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:20:04.189937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.575709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-244hr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-07T18:20:04.190003Z","caller":"traceutil/trace.go:171","msg":"trace[897309382] range","detail":"{range_begin:/registry/pods/kube-system/cilium-244hr; range_end:; response_count:1; response_revision:650; }","duration":"334.702902ms","start":"2026-09-07T18:20:03.855284Z","end":"2026-09-07T18:20:04.189987Z","steps":["trace[897309382] 'agreement among raft nodes before linearized reading' (duration: 334.527458ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:20:04.190021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:20:03.761901Z","time spent":"428.026177ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:20:04.190097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:20:03.855255Z","time spent":"334.785133ms","remote":"127.0.0.1:54642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-244hr\" "} {"level":"warn","ts":"2026-09-07T18:20:04.209086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.865515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-07T18:20:04.209165Z","caller":"traceutil/trace.go:171","msg":"trace[2060367350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"271.013827ms","start":"2026-09-07T18:20:03.938129Z","end":"2026-09-07T18:20:04.209143Z","steps":["trace[2060367350] 'agreement among raft nodes before linearized reading' (duration: 270.792683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:20:04.209305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.808781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T18:20:04.209427Z","caller":"traceutil/trace.go:171","msg":"trace[1781128039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"110.117038ms","start":"2026-09-07T18:20:04.09929Z","end":"2026-09-07T18:20:04.209407Z","steps":["trace[1781128039] 'agreement among raft nodes before linearized reading' (duration: 109.73878ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:20:20.255128Z","caller":"traceutil/trace.go:171","msg":"trace[2133953208] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"235.949863ms","start":"2026-09-07T18:20:20.019141Z","end":"2026-09-07T18:20:20.255091Z","steps":["trace[2133953208] 'process raft request' (duration: 235.715088ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:20:20.306654Z","caller":"traceutil/trace.go:171","msg":"trace[540382841] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:841; }","duration":"114.235433ms","start":"2026-09-07T18:20:20.192391Z","end":"2026-09-07T18:20:20.306626Z","steps":["trace[540382841] 'read index received' (duration: 62.49081ms)","trace[540382841] 'applied index is now lower than readState.Index' (duration: 51.743053ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:20:20.306698Z","caller":"traceutil/trace.go:171","msg":"trace[593044785] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"146.60882ms","start":"2026-09-07T18:20:20.160067Z","end":"2026-09-07T18:20:20.306676Z","steps":["trace[593044785] 'process raft request' (duration: 146.383246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:20:20.306805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.397406ms","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:20.306874Z","caller":"traceutil/trace.go:171","msg":"trace[775221115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"114.524869ms","start":"2026-09-07T18:20:20.192331Z","end":"2026-09-07T18:20:20.306856Z","steps":["trace[775221115] 'agreement among raft nodes before linearized reading' (duration: 114.399266ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:21:41.069308Z","caller":"traceutil/trace.go:171","msg":"trace[622817090] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1975; }","duration":"182.784837ms","start":"2026-09-07T18:21:40.88649Z","end":"2026-09-07T18:21:41.069275Z","steps":["trace[622817090] 'read index received' (duration: 182.521452ms)","trace[622817090] 'applied index is now lower than readState.Index' (duration: 261.245µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:21:41.069382Z","caller":"traceutil/trace.go:171","msg":"trace[1517495583] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"226.174234ms","start":"2026-09-07T18:21:40.843174Z","end":"2026-09-07T18:21:41.069348Z","steps":["trace[1517495583] 'process raft request' (duration: 225.868638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:21:41.069627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.816986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-07T18:21:41.069753Z","caller":"traceutil/trace.go:171","msg":"trace[1444693937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1907; }","duration":"181.976029ms","start":"2026-09-07T18:21:40.88775Z","end":"2026-09-07T18:21:41.069726Z","steps":["trace[1444693937] 'agreement among raft nodes before linearized reading' (duration: 181.756864ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:21:41.0698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.277918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-07T18:21:41.069943Z","caller":"traceutil/trace.go:171","msg":"trace[2111179104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1907; }","duration":"183.501132ms","start":"2026-09-07T18:21:40.88641Z","end":"2026-09-07T18:21:41.069911Z","steps":["trace[2111179104] 'agreement among raft nodes before linearized reading' (duration: 183.071973ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:21:50.042046Z","caller":"traceutil/trace.go:171","msg":"trace[1759304396] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"113.663898ms","start":"2026-09-07T18:21:49.928357Z","end":"2026-09-07T18:21:50.042021Z","steps":["trace[1759304396] 'process raft request' (duration: 113.502054ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:21:54.563302Z","caller":"traceutil/trace.go:171","msg":"trace[2107457932] linearizableReadLoop","detail":"{readStateIndex:2095; appliedIndex:2094; }","duration":"101.809596ms","start":"2026-09-07T18:21:54.46146Z","end":"2026-09-07T18:21:54.563269Z","steps":["trace[2107457932] 'read index received' (duration: 101.639412ms)","trace[2107457932] 'applied index is now lower than readState.Index' (duration: 169.384µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:21:54.563489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.9957ms","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:21:54.563524Z","caller":"traceutil/trace.go:171","msg":"trace[1519136219] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2022; }","duration":"102.082072ms","start":"2026-09-07T18:21:54.461432Z","end":"2026-09-07T18:21:54.563514Z","steps":["trace[1519136219] 'agreement among raft nodes before linearized reading' (duration: 101.9906ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:21:54.563596Z","caller":"traceutil/trace.go:171","msg":"trace[230351065] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"114.053696ms","start":"2026-09-07T18:21:54.449526Z","end":"2026-09-07T18:21:54.56358Z","steps":["trace[230351065] 'process raft request' (duration: 113.584637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:22:32.021211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.027658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:22:32.021381Z","caller":"traceutil/trace.go:171","msg":"trace[892603373] linearizableReadLoop","detail":"{readStateIndex:2318; appliedIndex:2317; }","duration":"143.149535ms","start":"2026-09-07T18:22:31.878207Z","end":"2026-09-07T18:22:32.021356Z","steps":["trace[892603373] 'read index received' (duration: 91.952µs)","trace[892603373] 'applied index is now lower than readState.Index' (duration: 143.055823ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:22:32.021571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.37344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:22:32.021626Z","caller":"traceutil/trace.go:171","msg":"trace[1625929420] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2237; }","duration":"143.490962ms","start":"2026-09-07T18:22:31.878116Z","end":"2026-09-07T18:22:32.021607Z","steps":["trace[1625929420] 'agreement among raft nodes before linearized reading' (duration: 143.313128ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:32.021704Z","caller":"traceutil/trace.go:171","msg":"trace[1404704252] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"279.585547ms","start":"2026-09-07T18:22:31.742031Z","end":"2026-09-07T18:22:32.021617Z","steps":["trace[1404704252] 'process raft request' (duration: 13.986498ms)","trace[1404704252] 'compare' (duration: 264.874224ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:22:32.57215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.974718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T18:22:32.572308Z","caller":"traceutil/trace.go:171","msg":"trace[1592852303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2238; }","duration":"117.198783ms","start":"2026-09-07T18:22:32.455077Z","end":"2026-09-07T18:22:32.572276Z","steps":["trace[1592852303] 'range keys from in-memory index tree' (duration: 116.602481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:58.858623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.190164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:23:58.858807Z","caller":"traceutil/trace.go:171","msg":"trace[183606746] linearizableReadLoop","detail":"{readStateIndex:3004; appliedIndex:3003; }","duration":"117.541748ms","start":"2026-09-07T18:23:58.741239Z","end":"2026-09-07T18:23:58.858781Z","steps":["trace[183606746] 'read index received' (duration: 73.431µs)","trace[183606746] 'applied index is now lower than readState.Index' (duration: 117.466247ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:23:58.858891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.655951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:23:58.858939Z","caller":"traceutil/trace.go:171","msg":"trace[670115067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2898; }","duration":"117.777183ms","start":"2026-09-07T18:23:58.741145Z","end":"2026-09-07T18:23:58.858923Z","steps":["trace[670115067] 'agreement among raft nodes before linearized reading' (duration: 117.695191ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:23:58.858984Z","caller":"traceutil/trace.go:171","msg":"trace[1933667717] transaction","detail":"{read_only:false; response_revision:2898; number_of_response:1; }","duration":"190.151116ms","start":"2026-09-07T18:23:58.668781Z","end":"2026-09-07T18:23:58.858932Z","steps":["trace[1933667717] 'process raft request' (duration: 53.528644ms)","trace[1933667717] 'compare' (duration: 135.984729ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:28:55.158021Z","caller":"traceutil/trace.go:171","msg":"trace[593921059] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"109.100063ms","start":"2026-09-07T18:28:55.04889Z","end":"2026-09-07T18:28:55.15799Z","steps":["trace[593921059] 'process raft request' (duration: 108.825367ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:28:55.191377Z","caller":"traceutil/trace.go:171","msg":"trace[381990912] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"108.172133ms","start":"2026-09-07T18:28:55.083148Z","end":"2026-09-07T18:28:55.19132Z","steps":["trace[381990912] 'process raft request' (duration: 107.723893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:01.217624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.760185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:29:01.217886Z","caller":"traceutil/trace.go:171","msg":"trace[160951986] linearizableReadLoop","detail":"{readStateIndex:5615; appliedIndex:5613; }","duration":"307.46201ms","start":"2026-09-07T18:29:00.910405Z","end":"2026-09-07T18:29:01.217867Z","steps":["trace[160951986] 'read index received' (duration: 110.470302ms)","trace[160951986] 'applied index is now lower than readState.Index' (duration: 196.990728ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:01.217908Z","caller":"traceutil/trace.go:171","msg":"trace[1015726256] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"311.058766ms","start":"2026-09-07T18:29:00.906816Z","end":"2026-09-07T18:29:01.217874Z","steps":["trace[1015726256] 'process raft request' (duration: 113.962114ms)","trace[1015726256] 'compare' (duration: 196.596742ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:29:01.218032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:00.906788Z","time spent":"311.196569ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":668,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T18:29:01.21804Z","caller":"traceutil/trace.go:171","msg":"trace[166494155] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"309.31101ms","start":"2026-09-07T18:29:00.90868Z","end":"2026-09-07T18:29:01.217991Z","steps":["trace[166494155] 'process raft request' (duration: 309.105546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:01.218153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.753197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/vector\" ","response":"range_response_count:1 size:5083"} {"level":"info","ts":"2026-09-07T18:29:01.218225Z","caller":"traceutil/trace.go:171","msg":"trace[2087319302] range","detail":"{range_begin:/registry/daemonsets/monitoring/vector; range_end:; response_count:1; response_revision:5413; }","duration":"307.844908ms","start":"2026-09-07T18:29:00.910363Z","end":"2026-09-07T18:29:01.218207Z","steps":["trace[2087319302] 'agreement among raft nodes before linearized reading' (duration: 307.609983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:01.21826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:00.91034Z","time spent":"307.91317ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":5106,"request content":"key:\"/registry/daemonsets/monitoring/vector\" "} {"level":"warn","ts":"2026-09-07T18:29:01.218266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.280443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-09-07T18:29:01.218293Z","caller":"traceutil/trace.go:171","msg":"trace[388545842] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5413; }","duration":"156.349005ms","start":"2026-09-07T18:29:01.061933Z","end":"2026-09-07T18:29:01.218282Z","steps":["trace[388545842] 'agreement among raft nodes before linearized reading' (duration: 156.061149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:01.218314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:29:00.908651Z","time spent":"309.521525ms","remote":"127.0.0.1:54642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:29:01.218154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.109569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:29:01.218449Z","caller":"traceutil/trace.go:171","msg":"trace[261290514] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5413; }","duration":"283.487787ms","start":"2026-09-07T18:29:00.934954Z","end":"2026-09-07T18:29:01.218442Z","steps":["trace[261290514] 'agreement among raft nodes before linearized reading' (duration: 283.043098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:01.218453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.401435ms","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:01.218529Z","caller":"traceutil/trace.go:171","msg":"trace[1269974761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5413; }","duration":"283.514878ms","start":"2026-09-07T18:29:00.934996Z","end":"2026-09-07T18:29:01.218511Z","steps":["trace[1269974761] 'agreement among raft nodes before linearized reading' (duration: 283.116609ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:09.909543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.809508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-07T18:29:09.909668Z","caller":"traceutil/trace.go:171","msg":"trace[1157598366] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5585; }","duration":"110.989713ms","start":"2026-09-07T18:29:09.798646Z","end":"2026-09-07T18:29:09.909636Z","steps":["trace[1157598366] 'range keys from in-memory index tree' (duration: 110.593294ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:10.173776Z","caller":"traceutil/trace.go:171","msg":"trace[781161082] transaction","detail":"{read_only:false; response_revision:5589; number_of_response:1; }","duration":"113.702419ms","start":"2026-09-07T18:29:10.06004Z","end":"2026-09-07T18:29:10.173743Z","steps":["trace[781161082] 'process raft request' (duration: 87.457408ms)","trace[781161082] 'compare' (duration: 26.040347ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:10.174271Z","caller":"traceutil/trace.go:171","msg":"trace[2049476629] transaction","detail":"{read_only:false; response_revision:5590; number_of_response:1; }","duration":"113.686409ms","start":"2026-09-07T18:29:10.060551Z","end":"2026-09-07T18:29:10.174238Z","steps":["trace[2049476629] 'process raft request' (duration: 113.134867ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:10.174403Z","caller":"traceutil/trace.go:171","msg":"trace[910179122] transaction","detail":"{read_only:false; response_revision:5591; number_of_response:1; }","duration":"113.436462ms","start":"2026-09-07T18:29:10.06093Z","end":"2026-09-07T18:29:10.174366Z","steps":["trace[910179122] 'process raft request' (duration: 113.032134ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:10.174794Z","caller":"traceutil/trace.go:171","msg":"trace[2136250151] linearizableReadLoop","detail":"{readStateIndex:5796; appliedIndex:5794; }","duration":"114.484725ms","start":"2026-09-07T18:29:10.060272Z","end":"2026-09-07T18:29:10.174757Z","steps":["trace[2136250151] 'read index received' (duration: 37.304184ms)","trace[2136250151] 'applied index is now lower than readState.Index' (duration: 77.177381ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:10.174924Z","caller":"traceutil/trace.go:171","msg":"trace[1939784906] transaction","detail":"{read_only:false; response_revision:5592; number_of_response:1; }","duration":"100.415969ms","start":"2026-09-07T18:29:10.074487Z","end":"2026-09-07T18:29:10.174903Z","steps":["trace[1939784906] 'process raft request' (duration: 99.552502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:10.17517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.860783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-07T18:29:10.17526Z","caller":"traceutil/trace.go:171","msg":"trace[1481046685] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5593; }","duration":"114.983815ms","start":"2026-09-07T18:29:10.060253Z","end":"2026-09-07T18:29:10.175237Z","steps":["trace[1481046685] 'agreement among raft nodes before linearized reading' (duration: 114.628768ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:13.398466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-09-07T18:29:13.478735Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"79.061422ms","hash":2051919889,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":31129600,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T18:29:13.47881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2051919889,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-09-07T18:29:15.643201Z","caller":"traceutil/trace.go:171","msg":"trace[1072300953] linearizableReadLoop","detail":"{readStateIndex:5873; appliedIndex:5872; }","duration":"210.390536ms","start":"2026-09-07T18:29:15.432783Z","end":"2026-09-07T18:29:15.643173Z","steps":["trace[1072300953] 'read index received' (duration: 169.601582ms)","trace[1072300953] 'applied index is now lower than readState.Index' (duration: 40.788044ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:15.643311Z","caller":"traceutil/trace.go:171","msg":"trace[163524408] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"259.063965ms","start":"2026-09-07T18:29:15.384237Z","end":"2026-09-07T18:29:15.643301Z","steps":["trace[163524408] 'process raft request' (duration: 218.16213ms)","trace[163524408] 'compare' (duration: 40.248092ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:29:15.643595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.788974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rvxmz\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-07T18:29:15.643631Z","caller":"traceutil/trace.go:171","msg":"trace[1849287659] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rvxmz; range_end:; response_count:1; response_revision:5663; }","duration":"210.884526ms","start":"2026-09-07T18:29:15.432738Z","end":"2026-09-07T18:29:15.643622Z","steps":["trace[1849287659] 'agreement among raft nodes before linearized reading' (duration: 210.674452ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:15.645185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.991605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:29:15.645285Z","caller":"traceutil/trace.go:171","msg":"trace[77804568] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5665; }","duration":"106.156538ms","start":"2026-09-07T18:29:15.539109Z","end":"2026-09-07T18:29:15.645265Z","steps":["trace[77804568] 'agreement among raft nodes before linearized reading' (duration: 105.981955ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:15.645846Z","caller":"traceutil/trace.go:171","msg":"trace[433353885] transaction","detail":"{read_only:false; response_revision:5665; number_of_response:1; }","duration":"119.680565ms","start":"2026-09-07T18:29:15.526148Z","end":"2026-09-07T18:29:15.645829Z","steps":["trace[433353885] 'process raft request' (duration: 118.870718ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:15.646303Z","caller":"traceutil/trace.go:171","msg":"trace[1320683694] transaction","detail":"{read_only:false; response_revision:5664; number_of_response:1; }","duration":"147.879501ms","start":"2026-09-07T18:29:15.498372Z","end":"2026-09-07T18:29:15.646252Z","steps":["trace[1320683694] 'process raft request' (duration: 146.493822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:37.988338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.773921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-07T18:29:37.988439Z","caller":"traceutil/trace.go:171","msg":"trace[1959742284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5990; }","duration":"106.947254ms","start":"2026-09-07T18:29:37.88147Z","end":"2026-09-07T18:29:37.988418Z","steps":["trace[1959742284] 'range keys from in-memory index tree' (duration: 106.556367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:37.988763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.151402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rvxmz\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-07T18:29:37.988896Z","caller":"traceutil/trace.go:171","msg":"trace[1822627241] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rvxmz; range_end:; response_count:1; response_revision:5990; }","duration":"101.377697ms","start":"2026-09-07T18:29:37.887496Z","end":"2026-09-07T18:29:37.988874Z","steps":["trace[1822627241] 'range keys from in-memory index tree' (duration: 100.497859ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:29:38.485135Z","caller":"traceutil/trace.go:171","msg":"trace[1765393646] linearizableReadLoop","detail":"{readStateIndex:6211; appliedIndex:6210; }","duration":"154.504302ms","start":"2026-09-07T18:29:38.330597Z","end":"2026-09-07T18:29:38.485102Z","steps":["trace[1765393646] 'read index received' (duration: 154.144214ms)","trace[1765393646] 'applied index is now lower than readState.Index' (duration: 359.098µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:29:38.485255Z","caller":"traceutil/trace.go:171","msg":"trace[1087183577] transaction","detail":"{read_only:false; response_revision:5994; number_of_response:1; }","duration":"167.576509ms","start":"2026-09-07T18:29:38.317641Z","end":"2026-09-07T18:29:38.485218Z","steps":["trace[1087183577] 'process raft request' (duration: 167.175281ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:29:38.485484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.851879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48be408b-829b-419a-a4cf-c1fff9afb0ab\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-07T18:29:38.485542Z","caller":"traceutil/trace.go:171","msg":"trace[731277321] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48be408b-829b-419a-a4cf-c1fff9afb0ab; range_end:; response_count:1; response_revision:5994; }","duration":"154.981572ms","start":"2026-09-07T18:29:38.330545Z","end":"2026-09-07T18:29:38.485527Z","steps":["trace[731277321] 'agreement among raft nodes before linearized reading' (duration: 154.738077ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:34:13.406273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5623} {"level":"info","ts":"2026-09-07T18:34:13.498606Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5623,"took":"90.134609ms","hash":1365314082,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":28536832,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T18:34:13.498687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1365314082,"revision":5623,"compact-revision":2978} {"level":"info","ts":"2026-09-07T18:36:09.858221Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023da.wal"} {"level":"info","ts":"2026-09-07T18:36:14.024578Z","caller":"traceutil/trace.go:171","msg":"trace[1008083410] transaction","detail":"{read_only:false; response_revision:8891; number_of_response:1; }","duration":"183.830205ms","start":"2026-09-07T18:36:13.840716Z","end":"2026-09-07T18:36:14.024546Z","steps":["trace[1008083410] 'process raft request' (duration: 183.669861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:25.575189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.981728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T18:36:25.575202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.766534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:36:25.575312Z","caller":"traceutil/trace.go:171","msg":"trace[536186497] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8952; }","duration":"273.146862ms","start":"2026-09-07T18:36:25.302145Z","end":"2026-09-07T18:36:25.575292Z","steps":["trace[536186497] 'range keys from in-memory index tree' (duration: 272.709703ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:25.575362Z","caller":"traceutil/trace.go:171","msg":"trace[162211356] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8952; }","duration":"272.983588ms","start":"2026-09-07T18:36:25.302354Z","end":"2026-09-07T18:36:25.575338Z","steps":["trace[162211356] 'count revisions from in-memory index tree' (duration: 272.648021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:25.575437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.043329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:36:25.575538Z","caller":"traceutil/trace.go:171","msg":"trace[625225820] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8952; }","duration":"273.173523ms","start":"2026-09-07T18:36:25.302334Z","end":"2026-09-07T18:36:25.575508Z","steps":["trace[625225820] 'range keys from in-memory index tree' (duration: 272.938098ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:25.575639Z","caller":"traceutil/trace.go:171","msg":"trace[559221359] transaction","detail":"{read_only:false; response_revision:8953; number_of_response:1; }","duration":"160.244154ms","start":"2026-09-07T18:36:25.415333Z","end":"2026-09-07T18:36:25.575577Z","steps":["trace[559221359] 'process raft request' (duration: 100.583087ms)","trace[559221359] 'compare' (duration: 59.256129ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:36:25.609418Z","caller":"traceutil/trace.go:171","msg":"trace[935834429] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"159.09165ms","start":"2026-09-07T18:36:25.450307Z","end":"2026-09-07T18:36:25.609398Z","steps":["trace[935834429] 'process raft request' (duration: 158.901145ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:25.609615Z","caller":"traceutil/trace.go:171","msg":"trace[851701109] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"143.001228ms","start":"2026-09-07T18:36:25.466588Z","end":"2026-09-07T18:36:25.609589Z","steps":["trace[851701109] 'process raft request' (duration: 142.756452ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:26.231774Z","caller":"traceutil/trace.go:171","msg":"trace[1447046936] transaction","detail":"{read_only:false; response_revision:8956; number_of_response:1; }","duration":"136.347657ms","start":"2026-09-07T18:36:26.09539Z","end":"2026-09-07T18:36:26.231738Z","steps":["trace[1447046936] 'process raft request' (duration: 136.114972ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:26.261939Z","caller":"traceutil/trace.go:171","msg":"trace[1809730405] transaction","detail":"{read_only:false; response_revision:8957; number_of_response:1; }","duration":"148.512214ms","start":"2026-09-07T18:36:26.113394Z","end":"2026-09-07T18:36:26.261906Z","steps":["trace[1809730405] 'process raft request' (duration: 148.359581ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:26.984343Z","caller":"traceutil/trace.go:171","msg":"trace[1157057467] transaction","detail":"{read_only:false; response_revision:8961; number_of_response:1; }","duration":"115.165146ms","start":"2026-09-07T18:36:26.869148Z","end":"2026-09-07T18:36:26.984313Z","steps":["trace[1157057467] 'process raft request' (duration: 114.359969ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:53.86381Z","caller":"traceutil/trace.go:171","msg":"trace[1441396200] linearizableReadLoop","detail":"{readStateIndex:9481; appliedIndex:9480; }","duration":"122.461261ms","start":"2026-09-07T18:36:53.741312Z","end":"2026-09-07T18:36:53.863774Z","steps":["trace[1441396200] 'read index received' (duration: 114.267958ms)","trace[1441396200] 'applied index is now lower than readState.Index' (duration: 8.191653ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:36:53.864035Z","caller":"traceutil/trace.go:171","msg":"trace[505115664] transaction","detail":"{read_only:false; response_revision:9158; number_of_response:1; }","duration":"132.103497ms","start":"2026-09-07T18:36:53.731855Z","end":"2026-09-07T18:36:53.863959Z","steps":["trace[505115664] 'process raft request' (duration: 123.705529ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:53.864089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.749738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/rook-ceph/csi-rbdplugin-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:36:53.864137Z","caller":"traceutil/trace.go:171","msg":"trace[1133195121] range","detail":"{range_begin:/registry/services/specs/rook-ceph/csi-rbdplugin-metrics; range_end:; response_count:0; response_revision:9158; }","duration":"122.86981ms","start":"2026-09-07T18:36:53.741256Z","end":"2026-09-07T18:36:53.864126Z","steps":["trace[1133195121] 'agreement among raft nodes before linearized reading' (duration: 122.762408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:53.864615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.065944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:36:53.864746Z","caller":"traceutil/trace.go:171","msg":"trace[1936873677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9158; }","duration":"123.256729ms","start":"2026-09-07T18:36:53.741462Z","end":"2026-09-07T18:36:53.864718Z","steps":["trace[1936873677] 'agreement among raft nodes before linearized reading' (duration: 123.014524ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:57.31161Z","caller":"traceutil/trace.go:171","msg":"trace[975657945] linearizableReadLoop","detail":"{readStateIndex:9502; appliedIndex:9501; }","duration":"142.302643ms","start":"2026-09-07T18:36:57.169274Z","end":"2026-09-07T18:36:57.311576Z","steps":["trace[975657945] 'read index received' (duration: 142.203581ms)","trace[975657945] 'applied index is now lower than readState.Index' (duration: 97.482µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:36:57.311742Z","caller":"traceutil/trace.go:171","msg":"trace[1322179050] transaction","detail":"{read_only:false; response_revision:9178; number_of_response:1; }","duration":"199.914757ms","start":"2026-09-07T18:36:57.111761Z","end":"2026-09-07T18:36:57.311676Z","steps":["trace[1322179050] 'process raft request' (duration: 199.61498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:36:57.311985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.664471ms","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:36:57.31208Z","caller":"traceutil/trace.go:171","msg":"trace[1586433692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9178; }","duration":"142.848065ms","start":"2026-09-07T18:36:57.169212Z","end":"2026-09-07T18:36:57.31206Z","steps":["trace[1586433692] 'agreement among raft nodes before linearized reading' (duration: 142.503157ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:36:57.46014Z","caller":"traceutil/trace.go:171","msg":"trace[1121999068] transaction","detail":"{read_only:false; response_revision:9179; number_of_response:1; }","duration":"141.437934ms","start":"2026-09-07T18:36:57.318663Z","end":"2026-09-07T18:36:57.460101Z","steps":["trace[1121999068] 'process raft request' (duration: 90.763777ms)","trace[1121999068] 'compare' (duration: 50.403451ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:37:09.646135Z","caller":"traceutil/trace.go:171","msg":"trace[1333886770] transaction","detail":"{read_only:false; response_revision:9242; number_of_response:1; }","duration":"111.746124ms","start":"2026-09-07T18:37:09.534364Z","end":"2026-09-07T18:37:09.64611Z","steps":["trace[1333886770] 'process raft request' (duration: 111.496769ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:37:52.702578Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0f50f8a6c5f7d06","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T18:37:52.71165Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-07T18:37:52.711749Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-07T18:39:13.412503Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7974} {"level":"info","ts":"2026-09-07T18:39:13.504157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7974,"took":"88.499147ms","hash":1711703730,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":25116672,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-07T18:39:13.504267Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1711703730,"revision":7974,"compact-revision":5623} {"level":"info","ts":"2026-09-07T18:44:13.432303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10340} {"level":"info","ts":"2026-09-07T18:44:13.540877Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10340,"took":"105.446841ms","hash":3650137153,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":25964544,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T18:44:13.54096Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3650137153,"revision":10340,"compact-revision":7974} {"level":"info","ts":"2026-09-07T18:49:13.439817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12507} {"level":"info","ts":"2026-09-07T18:49:13.533805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12507,"took":"89.621866ms","hash":2951783963,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":27496448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T18:49:13.53388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2951783963,"revision":12507,"compact-revision":10340} {"level":"info","ts":"2026-09-07T18:51:24.957928Z","caller":"traceutil/trace.go:171","msg":"trace[79512776] transaction","detail":"{read_only:false; response_revision:15338; number_of_response:1; }","duration":"138.103909ms","start":"2026-09-07T18:51:24.81979Z","end":"2026-09-07T18:51:24.957894Z","steps":["trace[79512776] 'process raft request' (duration: 137.69332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:51:51.596506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.056994ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:51:51.596677Z","caller":"traceutil/trace.go:171","msg":"trace[1861019241] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15667; }","duration":"154.265108ms","start":"2026-09-07T18:51:51.44239Z","end":"2026-09-07T18:51:51.596655Z","steps":["trace[1861019241] 'range keys from in-memory index tree' (duration: 154.035653ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:51.653406Z","caller":"traceutil/trace.go:171","msg":"trace[1062875137] transaction","detail":"{read_only:false; response_revision:15668; number_of_response:1; }","duration":"372.417189ms","start":"2026-09-07T18:51:51.280957Z","end":"2026-09-07T18:51:51.653375Z","steps":["trace[1062875137] 'process raft request' (duration: 372.215595ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:51:51.65391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:51:51.280918Z","time spent":"372.902259ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:51:51.654234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.953387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93e82a06-b0ef-476e-92c1-f6b50d49566d\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-09-07T18:51:51.654271Z","caller":"traceutil/trace.go:171","msg":"trace[351616512] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-93e82a06-b0ef-476e-92c1-f6b50d49566d; range_end:; response_count:1; response_revision:15668; }","duration":"298.053569ms","start":"2026-09-07T18:51:51.356204Z","end":"2026-09-07T18:51:51.654258Z","steps":["trace[351616512] 'agreement among raft nodes before linearized reading' (duration: 297.887965ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:51.654645Z","caller":"traceutil/trace.go:171","msg":"trace[362080660] linearizableReadLoop","detail":"{readStateIndex:16214; appliedIndex:16213; }","duration":"297.041137ms","start":"2026-09-07T18:51:51.356295Z","end":"2026-09-07T18:51:51.653337Z","steps":["trace[362080660] 'read index received' (duration: 296.799072ms)","trace[362080660] 'applied index is now lower than readState.Index' (duration: 241.375µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:51:51.654805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.179828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:51:51.654872Z","caller":"traceutil/trace.go:171","msg":"trace[1183141319] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:15668; }","duration":"210.292011ms","start":"2026-09-07T18:51:51.444569Z","end":"2026-09-07T18:51:51.654861Z","steps":["trace[1183141319] 'agreement among raft nodes before linearized reading' (duration: 210.189428ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:51:51.655156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.148094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-nova-server-0.18d31e96cd05e796\" ","response":"range_response_count:1 size:1001"} {"level":"info","ts":"2026-09-07T18:51:51.655234Z","caller":"traceutil/trace.go:171","msg":"trace[1827635896] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-nova-server-0.18d31e96cd05e796; range_end:; response_count:1; response_revision:15668; }","duration":"116.277666ms","start":"2026-09-07T18:51:51.538944Z","end":"2026-09-07T18:51:51.655221Z","steps":["trace[1827635896] 'agreement among raft nodes before linearized reading' (duration: 116.119803ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:51.767402Z","caller":"traceutil/trace.go:171","msg":"trace[229682089] transaction","detail":"{read_only:false; response_revision:15670; number_of_response:1; }","duration":"103.50421ms","start":"2026-09-07T18:51:51.663865Z","end":"2026-09-07T18:51:51.767369Z","steps":["trace[229682089] 'process raft request' (duration: 48.98108ms)","trace[229682089] 'compare' (duration: 53.878425ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:52:58.076153Z","caller":"traceutil/trace.go:171","msg":"trace[1903052817] transaction","detail":"{read_only:false; response_revision:16380; number_of_response:1; }","duration":"112.157967ms","start":"2026-09-07T18:52:57.963964Z","end":"2026-09-07T18:52:58.076122Z","steps":["trace[1903052817] 'process raft request' (duration: 111.954462ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:53:04.636454Z","caller":"traceutil/trace.go:171","msg":"trace[1134855504] linearizableReadLoop","detail":"{readStateIndex:16980; appliedIndex:16979; }","duration":"241.764422ms","start":"2026-09-07T18:53:04.394648Z","end":"2026-09-07T18:53:04.636412Z","steps":["trace[1134855504] 'read index received' (duration: 241.495536ms)","trace[1134855504] 'applied index is now lower than readState.Index' (duration: 267.426µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:53:04.636625Z","caller":"traceutil/trace.go:171","msg":"trace[289644852] transaction","detail":"{read_only:false; response_revision:16413; number_of_response:1; }","duration":"268.174854ms","start":"2026-09-07T18:53:04.368436Z","end":"2026-09-07T18:53:04.636611Z","steps":["trace[289644852] 'process raft request' (duration: 267.724513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:53:04.636814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.1389ms","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-07T18:53:04.636955Z","caller":"traceutil/trace.go:171","msg":"trace[1744104463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16413; }","duration":"242.315794ms","start":"2026-09-07T18:53:04.394588Z","end":"2026-09-07T18:53:04.636904Z","steps":["trace[1744104463] 'agreement among raft nodes before linearized reading' (duration: 242.063569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:53:04.668617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.446333ms","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:53:04.66871Z","caller":"traceutil/trace.go:171","msg":"trace[461064093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16414; }","duration":"240.572265ms","start":"2026-09-07T18:53:04.428115Z","end":"2026-09-07T18:53:04.668687Z","steps":["trace[461064093] 'agreement among raft nodes before linearized reading' (duration: 240.382901ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:53:04.668736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.331695ms","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-07T18:53:04.668822Z","caller":"traceutil/trace.go:171","msg":"trace[60521971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16414; }","duration":"261.474098ms","start":"2026-09-07T18:53:04.407325Z","end":"2026-09-07T18:53:04.6688Z","steps":["trace[60521971] 'agreement among raft nodes before linearized reading' (duration: 261.281894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:53:04.851492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.258895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T18:53:04.851827Z","caller":"traceutil/trace.go:171","msg":"trace[1287403051] transaction","detail":"{read_only:false; response_revision:16416; number_of_response:1; }","duration":"177.857389ms","start":"2026-09-07T18:53:04.673858Z","end":"2026-09-07T18:53:04.851716Z","steps":["trace[1287403051] 'process raft request' (duration: 67.256646ms)","trace[1287403051] 'compare' (duration: 109.98443ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:53:04.851923Z","caller":"traceutil/trace.go:171","msg":"trace[403567006] transaction","detail":"{read_only:false; response_revision:16417; number_of_response:1; }","duration":"177.694854ms","start":"2026-09-07T18:53:04.674196Z","end":"2026-09-07T18:53:04.851891Z","steps":["trace[403567006] 'process raft request' (duration: 177.426909ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:54:13.449088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14541} {"level":"info","ts":"2026-09-07T18:54:13.541317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14541,"took":"88.560265ms","hash":2955952300,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":29491200,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T18:54:13.541443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2955952300,"revision":14541,"compact-revision":12507} {"level":"info","ts":"2026-09-07T18:59:13.457266Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16832} {"level":"info","ts":"2026-09-07T18:59:13.566181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16832,"took":"105.316254ms","hash":1245933986,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":30662656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T18:59:13.566256Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1245933986,"revision":16832,"compact-revision":14541} {"level":"info","ts":"2026-09-07T18:59:55.597977Z","caller":"traceutil/trace.go:171","msg":"trace[160165121] linearizableReadLoop","detail":"{readStateIndex:19982; appliedIndex:19980; }","duration":"179.741669ms","start":"2026-09-07T18:59:55.418198Z","end":"2026-09-07T18:59:55.59794Z","steps":["trace[160165121] 'read index received' (duration: 34.115857ms)","trace[160165121] 'applied index is now lower than readState.Index' (duration: 145.624012ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:59:55.598339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.109636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-5f2wf\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-09-07T18:59:55.59839Z","caller":"traceutil/trace.go:171","msg":"trace[1747954705] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-5f2wf; range_end:; response_count:1; response_revision:19317; }","duration":"180.249949ms","start":"2026-09-07T18:59:55.418127Z","end":"2026-09-07T18:59:55.598377Z","steps":["trace[1747954705] 'agreement among raft nodes before linearized reading' (duration: 179.981923ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:59:55.598746Z","caller":"traceutil/trace.go:171","msg":"trace[36996181] transaction","detail":"{read_only:false; response_revision:19317; number_of_response:1; }","duration":"219.085747ms","start":"2026-09-07T18:59:55.379583Z","end":"2026-09-07T18:59:55.598669Z","steps":["trace[36996181] 'process raft request' (duration: 218.124777ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:59:59.174224Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0f50f8a6c5f7d06","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T18:59:59.180633Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-07T18:59:59.180752Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-07T19:00:03.434773Z","caller":"traceutil/trace.go:171","msg":"trace[1446454427] linearizableReadLoop","detail":"{readStateIndex:20056; appliedIndex:20055; }","duration":"245.474289ms","start":"2026-09-07T19:00:03.189262Z","end":"2026-09-07T19:00:03.434736Z","steps":["trace[1446454427] 'read index received' (duration: 245.192733ms)","trace[1446454427] 'applied index is now lower than readState.Index' (duration: 280.116µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:00:03.434934Z","caller":"traceutil/trace.go:171","msg":"trace[1019336822] transaction","detail":"{read_only:false; response_revision:19388; number_of_response:1; }","duration":"268.863617ms","start":"2026-09-07T19:00:03.166033Z","end":"2026-09-07T19:00:03.434897Z","steps":["trace[1019336822] 'process raft request' (duration: 268.407597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:03.435106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.822996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-07T19:00:03.435154Z","caller":"traceutil/trace.go:171","msg":"trace[1598386378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19388; }","duration":"245.901857ms","start":"2026-09-07T19:00:03.189239Z","end":"2026-09-07T19:00:03.435141Z","steps":["trace[1598386378] 'agreement among raft nodes before linearized reading' (duration: 245.687733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:03.442748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.002476ms","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:00:03.442742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.075763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29813460-49nvx\" ","response":"range_response_count:1 size:7039"} {"level":"info","ts":"2026-09-07T19:00:03.442813Z","caller":"traceutil/trace.go:171","msg":"trace[128174602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19389; }","duration":"227.162119ms","start":"2026-09-07T19:00:03.215637Z","end":"2026-09-07T19:00:03.442799Z","steps":["trace[128174602] 'agreement among raft nodes before linearized reading' (duration: 226.889763ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:00:03.442829Z","caller":"traceutil/trace.go:171","msg":"trace[1234534332] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29813460-49nvx; range_end:; response_count:1; response_revision:19389; }","duration":"170.255977ms","start":"2026-09-07T19:00:03.272552Z","end":"2026-09-07T19:00:03.442808Z","steps":["trace[1234534332] 'agreement among raft nodes before linearized reading' (duration: 169.998082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:03.89604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.938312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:00:03.896159Z","caller":"traceutil/trace.go:171","msg":"trace[938556475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19392; }","duration":"156.117606ms","start":"2026-09-07T19:00:03.740015Z","end":"2026-09-07T19:00:03.896133Z","steps":["trace[938556475] 'range keys from in-memory index tree' (duration: 155.83698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:03.89621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.149965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T19:00:03.896267Z","caller":"traceutil/trace.go:171","msg":"trace[410589495] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:19392; }","duration":"140.271878ms","start":"2026-09-07T19:00:03.755983Z","end":"2026-09-07T19:00:03.896255Z","steps":["trace[410589495] 'count revisions from in-memory index tree' (duration: 139.968812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:03.89636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.363056ms","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:00:03.896467Z","caller":"traceutil/trace.go:171","msg":"trace[1737252380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19392; }","duration":"260.51768ms","start":"2026-09-07T19:00:03.635917Z","end":"2026-09-07T19:00:03.896435Z","steps":["trace[1737252380] 'range keys from in-memory index tree' (duration: 260.166622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:03.896609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.852711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-5f2wf\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-09-07T19:00:03.89702Z","caller":"traceutil/trace.go:171","msg":"trace[245300905] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-5f2wf; range_end:; response_count:1; response_revision:19392; }","duration":"180.325841ms","start":"2026-09-07T19:00:03.716672Z","end":"2026-09-07T19:00:03.896998Z","steps":["trace[245300905] 'range keys from in-memory index tree' (duration: 179.660557ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:00:04.015542Z","caller":"traceutil/trace.go:171","msg":"trace[1414783321] linearizableReadLoop","detail":"{readStateIndex:20061; appliedIndex:20061; }","duration":"105.492157ms","start":"2026-09-07T19:00:03.909999Z","end":"2026-09-07T19:00:04.015492Z","steps":["trace[1414783321] 'read index received' (duration: 105.469327ms)","trace[1414783321] 'applied index is now lower than readState.Index' (duration: 18.07µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:00:04.015813Z","caller":"traceutil/trace.go:171","msg":"trace[567560219] transaction","detail":"{read_only:false; response_revision:19393; number_of_response:1; }","duration":"108.791648ms","start":"2026-09-07T19:00:03.906596Z","end":"2026-09-07T19:00:04.015388Z","steps":["trace[567560219] 'process raft request' (duration: 108.633625ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:00:04.016892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.867086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:962284"} {"level":"info","ts":"2026-09-07T19:00:04.016975Z","caller":"traceutil/trace.go:171","msg":"trace[1763150575] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:19393; }","duration":"107.003019ms","start":"2026-09-07T19:00:03.909955Z","end":"2026-09-07T19:00:04.016958Z","steps":["trace[1763150575] 'agreement among raft nodes before linearized reading' (duration: 105.797994ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:04:13.46444Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18990} {"level":"info","ts":"2026-09-07T19:04:13.572618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18990,"took":"103.469544ms","hash":1529513658,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":34111488,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-07T19:04:13.572688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1529513658,"revision":18990,"compact-revision":16832} {"level":"info","ts":"2026-09-07T19:06:22.389218Z","caller":"traceutil/trace.go:171","msg":"trace[1314753299] transaction","detail":"{read_only:false; response_revision:22333; number_of_response:1; }","duration":"227.23088ms","start":"2026-09-07T19:06:22.161957Z","end":"2026-09-07T19:06:22.389187Z","steps":["trace[1314753299] 'process raft request' (duration: 227.012215ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:06:22.631016Z","caller":"traceutil/trace.go:171","msg":"trace[115568042] transaction","detail":"{read_only:false; response_revision:22334; number_of_response:1; }","duration":"235.619388ms","start":"2026-09-07T19:06:22.395326Z","end":"2026-09-07T19:06:22.630945Z","steps":["trace[115568042] 'process raft request' (duration: 148.071884ms)","trace[115568042] 'compare' (duration: 87.090925ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:06:22.775489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.61209ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-07T19:06:22.775642Z","caller":"traceutil/trace.go:171","msg":"trace[1698342172] linearizableReadLoop","detail":"{readStateIndex:23097; appliedIndex:23095; }","duration":"325.738447ms","start":"2026-09-07T19:06:22.449879Z","end":"2026-09-07T19:06:22.775618Z","steps":["trace[1698342172] 'read index received' (duration: 93.538691ms)","trace[1698342172] 'applied index is now lower than readState.Index' (duration: 232.197736ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:06:22.775886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.968572ms","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:06:22.775923Z","caller":"traceutil/trace.go:171","msg":"trace[221238375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22334; }","duration":"326.107695ms","start":"2026-09-07T19:06:22.449805Z","end":"2026-09-07T19:06:22.775912Z","steps":["trace[221238375] 'agreement among raft nodes before linearized reading' (duration: 325.87004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:06:22.775963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:06:22.449777Z","time spent":"326.176617ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-07T19:06:23.917051Z","caller":"traceutil/trace.go:171","msg":"trace[1091943440] transaction","detail":"{read_only:false; response_revision:22336; number_of_response:1; }","duration":"143.932525ms","start":"2026-09-07T19:06:23.773055Z","end":"2026-09-07T19:06:23.916988Z","steps":["trace[1091943440] 'process raft request' (duration: 143.758972ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:06:23.935211Z","caller":"traceutil/trace.go:171","msg":"trace[1771387036] transaction","detail":"{read_only:false; response_revision:22337; number_of_response:1; }","duration":"157.062874ms","start":"2026-09-07T19:06:23.778124Z","end":"2026-09-07T19:06:23.935187Z","steps":["trace[1771387036] 'process raft request' (duration: 156.84157ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:06:23.935782Z","caller":"traceutil/trace.go:171","msg":"trace[728292244] transaction","detail":"{read_only:false; response_revision:22338; number_of_response:1; }","duration":"155.920801ms","start":"2026-09-07T19:06:23.779806Z","end":"2026-09-07T19:06:23.935727Z","steps":["trace[728292244] 'process raft request' (duration: 155.330458ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:06:24.206671Z","caller":"traceutil/trace.go:171","msg":"trace[30620534] transaction","detail":"{read_only:false; response_revision:22339; number_of_response:1; }","duration":"264.553435ms","start":"2026-09-07T19:06:23.942086Z","end":"2026-09-07T19:06:24.20664Z","steps":["trace[30620534] 'process raft request' (duration: 264.362001ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:06:24.208187Z","caller":"traceutil/trace.go:171","msg":"trace[1897135795] transaction","detail":"{read_only:false; response_revision:22340; number_of_response:1; }","duration":"265.488633ms","start":"2026-09-07T19:06:23.942648Z","end":"2026-09-07T19:06:24.208137Z","steps":["trace[1897135795] 'process raft request' (duration: 265.161626ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:06:24.208248Z","caller":"traceutil/trace.go:171","msg":"trace[1754874329] linearizableReadLoop","detail":"{readStateIndex:23103; appliedIndex:23101; }","duration":"166.573077ms","start":"2026-09-07T19:06:24.041365Z","end":"2026-09-07T19:06:24.207938Z","steps":["trace[1754874329] 'read index received' (duration: 165.048545ms)","trace[1754874329] 'applied index is now lower than readState.Index' (duration: 1.522952ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:06:24.2085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.110069ms","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:06:24.208562Z","caller":"traceutil/trace.go:171","msg":"trace[1472742470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22340; }","duration":"167.253312ms","start":"2026-09-07T19:06:24.041295Z","end":"2026-09-07T19:06:24.208548Z","steps":["trace[1472742470] 'agreement among raft nodes before linearized reading' (duration: 166.995656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:06:24.209413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.766904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:27 size:239997"} {"level":"info","ts":"2026-09-07T19:06:24.209466Z","caller":"traceutil/trace.go:171","msg":"trace[528778950] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:27; response_revision:22340; }","duration":"160.856747ms","start":"2026-09-07T19:06:24.048598Z","end":"2026-09-07T19:06:24.209454Z","steps":["trace[528778950] 'agreement among raft nodes before linearized reading' (duration: 159.856765ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:09:13.473737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21384} {"level":"info","ts":"2026-09-07T19:09:13.598967Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21384,"took":"121.236483ms","hash":4173955025,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T19:09:13.599033Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4173955025,"revision":21384,"compact-revision":18990} {"level":"info","ts":"2026-09-07T19:12:45.251475Z","caller":"traceutil/trace.go:171","msg":"trace[1250942426] transaction","detail":"{read_only:false; response_revision:26091; number_of_response:1; }","duration":"144.142492ms","start":"2026-09-07T19:12:45.107293Z","end":"2026-09-07T19:12:45.251435Z","steps":["trace[1250942426] 'process raft request' (duration: 143.898567ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:48.153934Z","caller":"traceutil/trace.go:171","msg":"trace[450034107] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"137.432258ms","start":"2026-09-07T19:12:48.016453Z","end":"2026-09-07T19:12:48.153885Z","steps":["trace[450034107] 'process raft request' (duration: 137.132642ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:48.3763Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006968.wal"} {"level":"info","ts":"2026-09-07T19:12:48.376893Z","caller":"traceutil/trace.go:171","msg":"trace[1222269822] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"245.880658ms","start":"2026-09-07T19:12:48.130974Z","end":"2026-09-07T19:12:48.376855Z","steps":["trace[1222269822] 'process raft request' (duration: 245.691344ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:48.45416Z","caller":"traceutil/trace.go:171","msg":"trace[1271573088] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"249.301302ms","start":"2026-09-07T19:12:48.204819Z","end":"2026-09-07T19:12:48.45412Z","steps":["trace[1271573088] 'process raft request' (duration: 224.176267ms)","trace[1271573088] 'compare' (duration: 24.053743ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:12:49.884091Z","caller":"traceutil/trace.go:171","msg":"trace[802591149] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"115.441759ms","start":"2026-09-07T19:12:49.768594Z","end":"2026-09-07T19:12:49.884035Z","steps":["trace[802591149] 'process raft request' (duration: 98.338325ms)","trace[802591149] 'compare' (duration: 16.974182ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:12:52.997067Z","caller":"traceutil/trace.go:171","msg":"trace[1751586186] linearizableReadLoop","detail":"{readStateIndex:27015; appliedIndex:27014; }","duration":"126.821583ms","start":"2026-09-07T19:12:52.870201Z","end":"2026-09-07T19:12:52.997022Z","steps":["trace[1751586186] 'read index received' (duration: 126.424425ms)","trace[1751586186] 'applied index is now lower than readState.Index' (duration: 396.008µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:12:52.997154Z","caller":"traceutil/trace.go:171","msg":"trace[576711592] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"158.820344ms","start":"2026-09-07T19:12:52.838277Z","end":"2026-09-07T19:12:52.997098Z","steps":["trace[576711592] 'process raft request' (duration: 158.572919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:12:52.997408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.624494ms","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:12:52.997517Z","caller":"traceutil/trace.go:171","msg":"trace[2131609774] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26145; }","duration":"100.768218ms","start":"2026-09-07T19:12:52.896716Z","end":"2026-09-07T19:12:52.997484Z","steps":["trace[2131609774] 'agreement among raft nodes before linearized reading' (duration: 100.560063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:12:52.997262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.043488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:12:52.997726Z","caller":"traceutil/trace.go:171","msg":"trace[1691134876] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:26145; }","duration":"127.499128ms","start":"2026-09-07T19:12:52.870177Z","end":"2026-09-07T19:12:52.997676Z","steps":["trace[1691134876] 'agreement among raft nodes before linearized reading' (duration: 127.011627ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:53.107504Z","caller":"traceutil/trace.go:171","msg":"trace[1490384427] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"103.582147ms","start":"2026-09-07T19:12:53.003893Z","end":"2026-09-07T19:12:53.107475Z","steps":["trace[1490384427] 'process raft request' (duration: 103.433494ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:53.22326Z","caller":"traceutil/trace.go:171","msg":"trace[1537172518] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"148.218067ms","start":"2026-09-07T19:12:53.075001Z","end":"2026-09-07T19:12:53.223219Z","steps":["trace[1537172518] 'process raft request' (duration: 119.601737ms)","trace[1537172518] 'compare' (duration: 28.225562ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:12:53.223433Z","caller":"traceutil/trace.go:171","msg":"trace[1398065398] linearizableReadLoop","detail":"{readStateIndex:27019; appliedIndex:27017; }","duration":"102.31932ms","start":"2026-09-07T19:12:53.121085Z","end":"2026-09-07T19:12:53.223405Z","steps":["trace[1398065398] 'read index received' (duration: 73.325432ms)","trace[1398065398] 'applied index is now lower than readState.Index' (duration: 28.991148ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:12:53.223625Z","caller":"traceutil/trace.go:171","msg":"trace[380152016] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"124.282379ms","start":"2026-09-07T19:12:53.099244Z","end":"2026-09-07T19:12:53.223526Z","steps":["trace[380152016] 'process raft request' (duration: 123.90632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:12:53.22374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.634587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-09-07T19:12:53.223781Z","caller":"traceutil/trace.go:171","msg":"trace[940494297] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26149; }","duration":"102.75415ms","start":"2026-09-07T19:12:53.121015Z","end":"2026-09-07T19:12:53.223769Z","steps":["trace[940494297] 'agreement among raft nodes before linearized reading' (duration: 102.549486ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:54.024789Z","caller":"traceutil/trace.go:171","msg":"trace[1039790647] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"115.973782ms","start":"2026-09-07T19:12:53.908785Z","end":"2026-09-07T19:12:54.024759Z","steps":["trace[1039790647] 'process raft request' (duration: 115.768677ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:54.19637Z","caller":"traceutil/trace.go:171","msg":"trace[1534511868] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"209.2996ms","start":"2026-09-07T19:12:53.987027Z","end":"2026-09-07T19:12:54.196327Z","steps":["trace[1534511868] 'process raft request' (duration: 149.475334ms)","trace[1534511868] 'compare' (duration: 59.652132ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:12:54.856738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.071591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:12:54.857195Z","caller":"traceutil/trace.go:171","msg":"trace[1819391716] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"296.547439ms","start":"2026-09-07T19:12:54.56061Z","end":"2026-09-07T19:12:54.857158Z","steps":["trace[1819391716] 'process raft request' (duration: 296.288174ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:12:54.857473Z","caller":"traceutil/trace.go:171","msg":"trace[1602702990] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"323.599586ms","start":"2026-09-07T19:12:54.533797Z","end":"2026-09-07T19:12:54.857396Z","steps":["trace[1602702990] 'process raft request' (duration: 85.717447ms)","trace[1602702990] 'compare' (duration: 236.646012ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:12:54.857875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:12:54.533778Z","time spent":"323.943823ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:13:04.183727Z","caller":"traceutil/trace.go:171","msg":"trace[1469003949] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"170.148476ms","start":"2026-09-07T19:13:04.013537Z","end":"2026-09-07T19:13:04.183686Z","steps":["trace[1469003949] 'process raft request' (duration: 160.241954ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:14.358457Z","caller":"traceutil/trace.go:171","msg":"trace[2095211754] transaction","detail":"{read_only:false; response_revision:26310; number_of_response:1; }","duration":"108.455491ms","start":"2026-09-07T19:13:14.249968Z","end":"2026-09-07T19:13:14.358423Z","steps":["trace[2095211754] 'process raft request' (duration: 108.278357ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:15.564641Z","caller":"traceutil/trace.go:171","msg":"trace[1377076866] linearizableReadLoop","detail":"{readStateIndex:27191; appliedIndex:27190; }","duration":"123.339498ms","start":"2026-09-07T19:13:15.441268Z","end":"2026-09-07T19:13:15.564607Z","steps":["trace[1377076866] 'read index received' (duration: 122.712494ms)","trace[1377076866] 'applied index is now lower than readState.Index' (duration: 625.334µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:15.564799Z","caller":"traceutil/trace.go:171","msg":"trace[93514666] transaction","detail":"{read_only:false; response_revision:26317; number_of_response:1; }","duration":"190.176582ms","start":"2026-09-07T19:13:15.374545Z","end":"2026-09-07T19:13:15.564721Z","steps":["trace[93514666] 'process raft request' (duration: 189.477686ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:15.5653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.001362ms","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:15.565356Z","caller":"traceutil/trace.go:171","msg":"trace[1841747514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26317; }","duration":"124.132775ms","start":"2026-09-07T19:13:15.441204Z","end":"2026-09-07T19:13:15.565337Z","steps":["trace[1841747514] 'agreement among raft nodes before linearized reading' (duration: 123.580923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:18.570561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.699732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:18.571005Z","caller":"traceutil/trace.go:171","msg":"trace[1439108814] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"177.37251ms","start":"2026-09-07T19:13:18.393599Z","end":"2026-09-07T19:13:18.570971Z","steps":["trace[1439108814] 'process raft request' (duration: 48.164227ms)","trace[1439108814] 'compare' (duration: 128.57508ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:20.306936Z","caller":"traceutil/trace.go:171","msg":"trace[1749505108] transaction","detail":"{read_only:false; response_revision:26352; number_of_response:1; }","duration":"100.90265ms","start":"2026-09-07T19:13:20.205961Z","end":"2026-09-07T19:13:20.306864Z","steps":["trace[1749505108] 'process raft request' (duration: 100.562123ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:20.750077Z","caller":"traceutil/trace.go:171","msg":"trace[1098500494] transaction","detail":"{read_only:false; response_revision:26355; number_of_response:1; }","duration":"173.519837ms","start":"2026-09-07T19:13:20.576492Z","end":"2026-09-07T19:13:20.750011Z","steps":["trace[1098500494] 'process raft request' (duration: 173.239731ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:20.881067Z","caller":"traceutil/trace.go:171","msg":"trace[390384490] transaction","detail":"{read_only:false; response_revision:26357; number_of_response:1; }","duration":"255.544655ms","start":"2026-09-07T19:13:20.625501Z","end":"2026-09-07T19:13:20.881046Z","steps":["trace[390384490] 'process raft request' (duration: 255.440823ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:20.881092Z","caller":"traceutil/trace.go:171","msg":"trace[1681689943] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"119.25488ms","start":"2026-09-07T19:13:20.761817Z","end":"2026-09-07T19:13:20.881072Z","steps":["trace[1681689943] 'process raft request' (duration: 119.203589ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:20.881086Z","caller":"traceutil/trace.go:171","msg":"trace[149708379] transaction","detail":"{read_only:false; response_revision:26356; number_of_response:1; }","duration":"299.954891ms","start":"2026-09-07T19:13:20.58108Z","end":"2026-09-07T19:13:20.881035Z","steps":["trace[149708379] 'process raft request' (duration: 246.159615ms)","trace[149708379] 'compare' (duration: 53.420759ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:20.881351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.100187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-db-sync-8ptt2.18d31fb3d7a9427b\" ","response":"range_response_count:1 size:834"} {"level":"info","ts":"2026-09-07T19:13:20.881426Z","caller":"traceutil/trace.go:171","msg":"trace[366079905] range","detail":"{range_begin:/registry/events/openstack/magnum-db-sync-8ptt2.18d31fb3d7a9427b; range_end:; response_count:1; response_revision:26358; }","duration":"165.226311ms","start":"2026-09-07T19:13:20.716179Z","end":"2026-09-07T19:13:20.881405Z","steps":["trace[366079905] 'agreement among raft nodes before linearized reading' (duration: 164.998895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:20.881468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:20.58107Z","time spent":"300.31529ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:13:20.881141Z","caller":"traceutil/trace.go:171","msg":"trace[1771445567] linearizableReadLoop","detail":"{readStateIndex:27232; appliedIndex:27230; }","duration":"164.899233ms","start":"2026-09-07T19:13:20.716228Z","end":"2026-09-07T19:13:20.881127Z","steps":["trace[1771445567] 'read index received' (duration: 33.76661ms)","trace[1771445567] 'applied index is now lower than readState.Index' (duration: 131.131823ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:21.245113Z","caller":"traceutil/trace.go:171","msg":"trace[1892423713] transaction","detail":"{read_only:false; response_revision:26360; number_of_response:1; }","duration":"260.448559ms","start":"2026-09-07T19:13:20.984638Z","end":"2026-09-07T19:13:21.245087Z","steps":["trace[1892423713] 'process raft request' (duration: 260.304476ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:22.014531Z","caller":"traceutil/trace.go:171","msg":"trace[1628177387] transaction","detail":"{read_only:false; response_revision:26362; number_of_response:1; }","duration":"454.259979ms","start":"2026-09-07T19:13:21.560206Z","end":"2026-09-07T19:13:22.014466Z","steps":["trace[1628177387] 'process raft request' (duration: 454.006714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.014806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:21.560173Z","time spent":"454.480714ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:13:22.015451Z","caller":"traceutil/trace.go:171","msg":"trace[173164040] linearizableReadLoop","detail":"{readStateIndex:27237; appliedIndex:27237; }","duration":"381.965668ms","start":"2026-09-07T19:13:21.633467Z","end":"2026-09-07T19:13:22.015432Z","steps":["trace[173164040] 'read index received' (duration: 381.956898ms)","trace[173164040] 'applied index is now lower than readState.Index' (duration: 7.24µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:22.0156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.121331ms","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:22.015654Z","caller":"traceutil/trace.go:171","msg":"trace[800105884] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26362; }","duration":"382.208003ms","start":"2026-09-07T19:13:21.633431Z","end":"2026-09-07T19:13:22.015639Z","steps":["trace[800105884] 'agreement among raft nodes before linearized reading' (duration: 382.114461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.01571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:21.633416Z","time spent":"382.278685ms","remote":"127.0.0.1:54688","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:22.015701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.188953ms","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:22.015808Z","caller":"traceutil/trace.go:171","msg":"trace[1444575245] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26362; }","duration":"382.361246ms","start":"2026-09-07T19:13:21.63342Z","end":"2026-09-07T19:13:22.015781Z","steps":["trace[1444575245] 'agreement among raft nodes before linearized reading' (duration: 382.140532ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.015886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:21.633401Z","time spent":"382.474229ms","remote":"127.0.0.1:54688","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":"info","ts":"2026-09-07T19:13:22.052456Z","caller":"traceutil/trace.go:171","msg":"trace[365855998] transaction","detail":"{read_only:false; response_revision:26363; number_of_response:1; }","duration":"256.126498ms","start":"2026-09-07T19:13:21.796291Z","end":"2026-09-07T19:13:22.052418Z","steps":["trace[365855998] 'process raft request' (duration: 255.857311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.052624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.988988ms","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:22.052695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.970418ms","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:22.052779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.64188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"warn","ts":"2026-09-07T19:13:22.052763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.123219ms","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.052853Z","caller":"traceutil/trace.go:171","msg":"trace[840180418] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26363; }","duration":"418.749502ms","start":"2026-09-07T19:13:21.634081Z","end":"2026-09-07T19:13:22.05283Z","steps":["trace[840180418] 'agreement among raft nodes before linearized reading' (duration: 418.477307ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:22.052812Z","caller":"traceutil/trace.go:171","msg":"trace[257012430] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26363; }","duration":"419.12557ms","start":"2026-09-07T19:13:21.633644Z","end":"2026-09-07T19:13:22.05277Z","steps":["trace[257012430] 'agreement among raft nodes before linearized reading' (duration: 418.974317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.052882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.841986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-07T19:13:22.052956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:21.63362Z","time spent":"419.324615ms","remote":"127.0.0.1:54688","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:22.052941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.606429ms","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:22.052975Z","caller":"traceutil/trace.go:171","msg":"trace[1328010214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26363; }","duration":"381.963409ms","start":"2026-09-07T19:13:21.670994Z","end":"2026-09-07T19:13:22.052958Z","steps":["trace[1328010214] 'agreement among raft nodes before linearized reading' (duration: 381.477929ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:22.053032Z","caller":"traceutil/trace.go:171","msg":"trace[836326341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26363; }","duration":"212.764273ms","start":"2026-09-07T19:13:21.840245Z","end":"2026-09-07T19:13:22.053009Z","steps":["trace[836326341] 'agreement among raft nodes before linearized reading' (duration: 212.461047ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:22.052719Z","caller":"traceutil/trace.go:171","msg":"trace[1896549045] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26363; }","duration":"419.118471ms","start":"2026-09-07T19:13:21.633578Z","end":"2026-09-07T19:13:22.052696Z","steps":["trace[1896549045] 'agreement among raft nodes before linearized reading' (duration: 418.927377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.05309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:21.633573Z","time spent":"419.509459ms","remote":"127.0.0.1:54688","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:22.052963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:21.634047Z","time spent":"418.892636ms","remote":"127.0.0.1:54688","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":"info","ts":"2026-09-07T19:13:22.052875Z","caller":"traceutil/trace.go:171","msg":"trace[1498133752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26363; }","duration":"181.253192ms","start":"2026-09-07T19:13:21.871599Z","end":"2026-09-07T19:13:22.052852Z","steps":["trace[1498133752] 'agreement among raft nodes before linearized reading' (duration: 180.986386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.052541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.895976ms","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:22.053346Z","caller":"traceutil/trace.go:171","msg":"trace[1200835244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26363; }","duration":"312.734074ms","start":"2026-09-07T19:13:21.740596Z","end":"2026-09-07T19:13:22.05333Z","steps":["trace[1200835244] 'agreement among raft nodes before linearized reading' (duration: 311.831765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.053392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:21.740531Z","time spent":"312.849016ms","remote":"127.0.0.1:54274","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:22.05304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:21.670974Z","time spent":"382.055742ms","remote":"127.0.0.1:54730","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":"info","ts":"2026-09-07T19:13:22.215251Z","caller":"traceutil/trace.go:171","msg":"trace[1256888917] transaction","detail":"{read_only:false; response_revision:26366; number_of_response:1; }","duration":"155.273169ms","start":"2026-09-07T19:13:22.059942Z","end":"2026-09-07T19:13:22.215216Z","steps":["trace[1256888917] 'process raft request' (duration: 155.164076ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:22.21526Z","caller":"traceutil/trace.go:171","msg":"trace[956382102] transaction","detail":"{read_only:false; response_revision:26365; number_of_response:1; }","duration":"155.899092ms","start":"2026-09-07T19:13:22.05931Z","end":"2026-09-07T19:13:22.21521Z","steps":["trace[956382102] 'process raft request' (duration: 155.543195ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:22.215455Z","caller":"traceutil/trace.go:171","msg":"trace[1816112146] linearizableReadLoop","detail":"{readStateIndex:27242; appliedIndex:27239; }","duration":"122.49413ms","start":"2026-09-07T19:13:22.092926Z","end":"2026-09-07T19:13:22.21542Z","steps":["trace[1816112146] 'read index received' (duration: 121.990099ms)","trace[1816112146] 'applied index is now lower than readState.Index' (duration: 502.811µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:22.215715Z","caller":"traceutil/trace.go:171","msg":"trace[2142852990] transaction","detail":"{read_only:false; response_revision:26367; number_of_response:1; }","duration":"155.028584ms","start":"2026-09-07T19:13:22.060548Z","end":"2026-09-07T19:13:22.215576Z","steps":["trace[2142852990] 'process raft request' (duration: 154.616045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.215834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.041204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T19:13:22.215927Z","caller":"traceutil/trace.go:171","msg":"trace[1636238862] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:26367; }","duration":"116.243718ms","start":"2026-09-07T19:13:22.099642Z","end":"2026-09-07T19:13:22.215886Z","steps":["trace[1636238862] 'agreement among raft nodes before linearized reading' (duration: 116.049414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:22.215925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.92816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:22.216004Z","caller":"traceutil/trace.go:171","msg":"trace[1612037923] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26367; }","duration":"123.122794ms","start":"2026-09-07T19:13:22.092856Z","end":"2026-09-07T19:13:22.215979Z","steps":["trace[1612037923] 'agreement among raft nodes before linearized reading' (duration: 122.897169ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:22.875019Z","caller":"traceutil/trace.go:171","msg":"trace[494729296] transaction","detail":"{read_only:false; response_revision:26370; number_of_response:1; }","duration":"118.722939ms","start":"2026-09-07T19:13:22.756265Z","end":"2026-09-07T19:13:22.874988Z","steps":["trace[494729296] 'process raft request' (duration: 118.496894ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:23.008115Z","caller":"traceutil/trace.go:171","msg":"trace[1691811345] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"118.981535ms","start":"2026-09-07T19:13:22.889104Z","end":"2026-09-07T19:13:23.008085Z","steps":["trace[1691811345] 'process raft request' (duration: 118.908864ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:23.008244Z","caller":"traceutil/trace.go:171","msg":"trace[1539644891] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"120.448616ms","start":"2026-09-07T19:13:22.887729Z","end":"2026-09-07T19:13:23.008178Z","steps":["trace[1539644891] 'process raft request' (duration: 68.617741ms)","trace[1539644891] 'compare' (duration: 51.458567ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:24.239517Z","caller":"traceutil/trace.go:171","msg":"trace[1773612303] linearizableReadLoop","detail":"{readStateIndex:27252; appliedIndex:27251; }","duration":"180.524566ms","start":"2026-09-07T19:13:24.058967Z","end":"2026-09-07T19:13:24.239491Z","steps":["trace[1773612303] 'read index received' (duration: 180.263331ms)","trace[1773612303] 'applied index is now lower than readState.Index' (duration: 259.245µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:24.239638Z","caller":"traceutil/trace.go:171","msg":"trace[2108703119] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"181.043427ms","start":"2026-09-07T19:13:24.058565Z","end":"2026-09-07T19:13:24.239609Z","steps":["trace[2108703119] 'process raft request' (duration: 180.661849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:24.239827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.798552ms","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:24.239865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.30089ms","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:24.23999Z","caller":"traceutil/trace.go:171","msg":"trace[1525413319] 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:26376; }","duration":"118.492874ms","start":"2026-09-07T19:13:24.121476Z","end":"2026-09-07T19:13:24.239969Z","steps":["trace[1525413319] 'agreement among raft nodes before linearized reading' (duration: 118.247399ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:24.239997Z","caller":"traceutil/trace.go:171","msg":"trace[1153371981] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26376; }","duration":"181.023146ms","start":"2026-09-07T19:13:24.058941Z","end":"2026-09-07T19:13:24.239964Z","steps":["trace[1153371981] 'agreement among raft nodes before linearized reading' (duration: 180.72738ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:24.401418Z","caller":"traceutil/trace.go:171","msg":"trace[2059806584] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"155.35665ms","start":"2026-09-07T19:13:24.246021Z","end":"2026-09-07T19:13:24.401378Z","steps":["trace[2059806584] 'process raft request' (duration: 80.342891ms)","trace[2059806584] 'compare' (duration: 74.843755ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:24.417944Z","caller":"traceutil/trace.go:171","msg":"trace[1511639395] transaction","detail":"{read_only:false; response_revision:26380; number_of_response:1; }","duration":"170.278108ms","start":"2026-09-07T19:13:24.247596Z","end":"2026-09-07T19:13:24.417874Z","steps":["trace[1511639395] 'process raft request' (duration: 170.189897ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:24.418223Z","caller":"traceutil/trace.go:171","msg":"trace[1617686783] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"172.042976ms","start":"2026-09-07T19:13:24.246169Z","end":"2026-09-07T19:13:24.418212Z","steps":["trace[1617686783] 'process raft request' (duration: 171.30061ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:24.418323Z","caller":"traceutil/trace.go:171","msg":"trace[766666290] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"171.593465ms","start":"2026-09-07T19:13:24.246708Z","end":"2026-09-07T19:13:24.418301Z","steps":["trace[766666290] 'process raft request' (duration: 171.028634ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:24.562907Z","caller":"traceutil/trace.go:171","msg":"trace[484946784] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"202.077555ms","start":"2026-09-07T19:13:24.360752Z","end":"2026-09-07T19:13:24.562829Z","steps":["trace[484946784] 'process raft request' (duration: 179.219717ms)","trace[484946784] 'compare' (duration: 22.316006ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:24.563748Z","caller":"traceutil/trace.go:171","msg":"trace[999307366] transaction","detail":"{read_only:false; response_revision:26382; number_of_response:1; }","duration":"161.823878ms","start":"2026-09-07T19:13:24.401907Z","end":"2026-09-07T19:13:24.563731Z","steps":["trace[999307366] 'process raft request' (duration: 161.325188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:25.163057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.91477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:25.163167Z","caller":"traceutil/trace.go:171","msg":"trace[1838878602] transaction","detail":"{read_only:false; response_revision:26386; number_of_response:1; }","duration":"148.679348ms","start":"2026-09-07T19:13:25.014468Z","end":"2026-09-07T19:13:25.163148Z","steps":["trace[1838878602] 'process raft request' (duration: 40.581854ms)","trace[1838878602] 'compare' (duration: 107.770627ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:25.331045Z","caller":"traceutil/trace.go:171","msg":"trace[1845804057] linearizableReadLoop","detail":"{readStateIndex:27263; appliedIndex:27262; }","duration":"275.820548ms","start":"2026-09-07T19:13:25.055195Z","end":"2026-09-07T19:13:25.331015Z","steps":["trace[1845804057] 'read index received' (duration: 217.761321ms)","trace[1845804057] 'applied index is now lower than readState.Index' (duration: 58.058097ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:25.331095Z","caller":"traceutil/trace.go:171","msg":"trace[2057775727] transaction","detail":"{read_only:false; response_revision:26387; number_of_response:1; }","duration":"315.675437ms","start":"2026-09-07T19:13:25.015387Z","end":"2026-09-07T19:13:25.331062Z","steps":["trace[2057775727] 'process raft request' (duration: 257.65071ms)","trace[2057775727] 'compare' (duration: 57.758311ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:25.331204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.006341ms","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:13:25.331237Z","caller":"traceutil/trace.go:171","msg":"trace[739245224] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26387; }","duration":"276.064132ms","start":"2026-09-07T19:13:25.055162Z","end":"2026-09-07T19:13:25.331226Z","steps":["trace[739245224] 'agreement among raft nodes before linearized reading' (duration: 275.96491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:25.331271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.048498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:25.331302Z","caller":"traceutil/trace.go:171","msg":"trace[1559230196] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:26387; }","duration":"212.1334ms","start":"2026-09-07T19:13:25.119158Z","end":"2026-09-07T19:13:25.331292Z","steps":["trace[1559230196] 'agreement among raft nodes before linearized reading' (duration: 212.075188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:25.331313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:25.015357Z","time spent":"315.87258ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:13:25.331336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.027577ms","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:25.331419Z","caller":"traceutil/trace.go:171","msg":"trace[378698164] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26387; }","duration":"166.1624ms","start":"2026-09-07T19:13:25.165238Z","end":"2026-09-07T19:13:25.3314Z","steps":["trace[378698164] 'agreement among raft nodes before linearized reading' (duration: 166.004547ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:25.530855Z","caller":"traceutil/trace.go:171","msg":"trace[1585062568] transaction","detail":"{read_only:false; response_revision:26388; number_of_response:1; }","duration":"111.881274ms","start":"2026-09-07T19:13:25.418943Z","end":"2026-09-07T19:13:25.530824Z","steps":["trace[1585062568] 'process raft request' (duration: 39.796748ms)","trace[1585062568] 'compare' (duration: 71.880901ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:26.217765Z","caller":"traceutil/trace.go:171","msg":"trace[1700728362] linearizableReadLoop","detail":"{readStateIndex:27265; appliedIndex:27264; }","duration":"154.309907ms","start":"2026-09-07T19:13:26.063431Z","end":"2026-09-07T19:13:26.217741Z","steps":["trace[1700728362] 'read index received' (duration: 154.049632ms)","trace[1700728362] 'applied index is now lower than readState.Index' (duration: 259.075µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:26.217848Z","caller":"traceutil/trace.go:171","msg":"trace[86505075] transaction","detail":"{read_only:false; response_revision:26389; number_of_response:1; }","duration":"160.955739ms","start":"2026-09-07T19:13:26.056854Z","end":"2026-09-07T19:13:26.21781Z","steps":["trace[86505075] 'process raft request' (duration: 160.709303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.217967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.507401ms","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:26.218006Z","caller":"traceutil/trace.go:171","msg":"trace[877263099] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26389; }","duration":"154.581063ms","start":"2026-09-07T19:13:26.063416Z","end":"2026-09-07T19:13:26.217997Z","steps":["trace[877263099] 'agreement among raft nodes before linearized reading' (duration: 154.487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.21809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.625424ms","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:26.218146Z","caller":"traceutil/trace.go:171","msg":"trace[1306190752] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26389; }","duration":"154.741427ms","start":"2026-09-07T19:13:26.063392Z","end":"2026-09-07T19:13:26.218133Z","steps":["trace[1306190752] 'agreement among raft nodes before linearized reading' (duration: 154.45887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.218505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.929063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:26.218614Z","caller":"traceutil/trace.go:171","msg":"trace[408743110] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26389; }","duration":"110.110887ms","start":"2026-09-07T19:13:26.108477Z","end":"2026-09-07T19:13:26.218588Z","steps":["trace[408743110] 'agreement among raft nodes before linearized reading' (duration: 109.889792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:26.461727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.133093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:26.46221Z","caller":"traceutil/trace.go:171","msg":"trace[1281624897] transaction","detail":"{read_only:false; response_revision:26391; number_of_response:1; }","duration":"211.948186ms","start":"2026-09-07T19:13:26.250231Z","end":"2026-09-07T19:13:26.462179Z","steps":["trace[1281624897] 'process raft request' (duration: 58.274512ms)","trace[1281624897] 'compare' (duration: 152.713803ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:26.692021Z","caller":"traceutil/trace.go:171","msg":"trace[1817386618] transaction","detail":"{read_only:false; response_revision:26392; number_of_response:1; }","duration":"225.23345ms","start":"2026-09-07T19:13:26.466749Z","end":"2026-09-07T19:13:26.691983Z","steps":["trace[1817386618] 'process raft request' (duration: 224.708709ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:26.72729Z","caller":"traceutil/trace.go:171","msg":"trace[35691380] transaction","detail":"{read_only:false; response_revision:26393; number_of_response:1; }","duration":"258.203861ms","start":"2026-09-07T19:13:26.469036Z","end":"2026-09-07T19:13:26.727239Z","steps":["trace[35691380] 'process raft request' (duration: 257.934926ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:26.727344Z","caller":"traceutil/trace.go:171","msg":"trace[1889515744] transaction","detail":"{read_only:false; response_revision:26397; number_of_response:1; }","duration":"158.146199ms","start":"2026-09-07T19:13:26.56917Z","end":"2026-09-07T19:13:26.727316Z","steps":["trace[1889515744] 'process raft request' (duration: 158.097858ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:26.727354Z","caller":"traceutil/trace.go:171","msg":"trace[320226005] transaction","detail":"{read_only:false; response_revision:26394; number_of_response:1; }","duration":"258.182591ms","start":"2026-09-07T19:13:26.469125Z","end":"2026-09-07T19:13:26.727307Z","steps":["trace[320226005] 'process raft request' (duration: 258.004317ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:26.727428Z","caller":"traceutil/trace.go:171","msg":"trace[1937540919] transaction","detail":"{read_only:false; response_revision:26395; number_of_response:1; }","duration":"257.287182ms","start":"2026-09-07T19:13:26.470108Z","end":"2026-09-07T19:13:26.727396Z","steps":["trace[1937540919] 'process raft request' (duration: 257.065648ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:26.72747Z","caller":"traceutil/trace.go:171","msg":"trace[2123181174] transaction","detail":"{read_only:false; response_revision:26396; number_of_response:1; }","duration":"159.228491ms","start":"2026-09-07T19:13:26.568209Z","end":"2026-09-07T19:13:26.727438Z","steps":["trace[2123181174] 'process raft request' (duration: 159.002737ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:27.091299Z","caller":"traceutil/trace.go:171","msg":"trace[1669205101] transaction","detail":"{read_only:false; response_revision:26399; number_of_response:1; }","duration":"122.539431ms","start":"2026-09-07T19:13:26.968716Z","end":"2026-09-07T19:13:27.091255Z","steps":["trace[1669205101] 'process raft request' (duration: 122.293656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:27.232624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.862831ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-07T19:13:27.569518Z","caller":"traceutil/trace.go:171","msg":"trace[305809792] linearizableReadLoop","detail":"{readStateIndex:27279; appliedIndex:27278; }","duration":"113.806755ms","start":"2026-09-07T19:13:27.45568Z","end":"2026-09-07T19:13:27.569487Z","steps":["trace[305809792] 'read index received' (duration: 53.399838ms)","trace[305809792] 'applied index is now lower than readState.Index' (duration: 60.404917ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:27.569605Z","caller":"traceutil/trace.go:171","msg":"trace[974945437] transaction","detail":"{read_only:false; response_revision:26402; number_of_response:1; }","duration":"232.119766ms","start":"2026-09-07T19:13:27.337448Z","end":"2026-09-07T19:13:27.569568Z","steps":["trace[974945437] 'process raft request' (duration: 171.645628ms)","trace[974945437] 'compare' (duration: 60.157751ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:27.56973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.03722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T19:13:27.56977Z","caller":"traceutil/trace.go:171","msg":"trace[2023120530] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26402; }","duration":"114.152112ms","start":"2026-09-07T19:13:27.455605Z","end":"2026-09-07T19:13:27.569758Z","steps":["trace[2023120530] 'agreement among raft nodes before linearized reading' (duration: 114.033979ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:27.749006Z","caller":"traceutil/trace.go:171","msg":"trace[1347627748] linearizableReadLoop","detail":"{readStateIndex:27280; appliedIndex:27279; }","duration":"179.374802ms","start":"2026-09-07T19:13:27.569601Z","end":"2026-09-07T19:13:27.748976Z","steps":["trace[1347627748] 'read index received' (duration: 103.7138ms)","trace[1347627748] 'applied index is now lower than readState.Index' (duration: 75.659252ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:27.749227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.782471ms","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:27.749229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.054656ms","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:27.749275Z","caller":"traceutil/trace.go:171","msg":"trace[242210452] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26403; }","duration":"210.907224ms","start":"2026-09-07T19:13:27.538351Z","end":"2026-09-07T19:13:27.749258Z","steps":["trace[242210452] 'agreement among raft nodes before linearized reading' (duration: 210.815082ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:27.74924Z","caller":"traceutil/trace.go:171","msg":"trace[1193303443] transaction","detail":"{read_only:false; response_revision:26403; number_of_response:1; }","duration":"209.504724ms","start":"2026-09-07T19:13:27.539693Z","end":"2026-09-07T19:13:27.749197Z","steps":["trace[1193303443] 'process raft request' (duration: 133.691989ms)","trace[1193303443] 'compare' (duration: 75.348165ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:27.749304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.116297ms","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:27.749236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.447533ms","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:27.749284Z","caller":"traceutil/trace.go:171","msg":"trace[2072455183] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26403; }","duration":"210.381562ms","start":"2026-09-07T19:13:27.538887Z","end":"2026-09-07T19:13:27.749269Z","steps":["trace[2072455183] 'agreement among raft nodes before linearized reading' (duration: 210.24901ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:27.749532Z","caller":"traceutil/trace.go:171","msg":"trace[2075578248] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26403; }","duration":"209.79685ms","start":"2026-09-07T19:13:27.539706Z","end":"2026-09-07T19:13:27.749503Z","steps":["trace[2075578248] 'agreement among raft nodes before linearized reading' (duration: 209.31185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:27.749644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.647849ms","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:27.749439Z","caller":"traceutil/trace.go:171","msg":"trace[1415984887] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26403; }","duration":"210.317081ms","start":"2026-09-07T19:13:27.539075Z","end":"2026-09-07T19:13:27.749392Z","steps":["trace[1415984887] 'agreement among raft nodes before linearized reading' (duration: 210.022045ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:27.749715Z","caller":"traceutil/trace.go:171","msg":"trace[471507955] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26403; }","duration":"211.760711ms","start":"2026-09-07T19:13:27.53794Z","end":"2026-09-07T19:13:27.749701Z","steps":["trace[471507955] 'agreement among raft nodes before linearized reading' (duration: 211.551677ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:28.396305Z","caller":"traceutil/trace.go:171","msg":"trace[2036663641] transaction","detail":"{read_only:false; response_revision:26406; number_of_response:1; }","duration":"116.276827ms","start":"2026-09-07T19:13:28.279977Z","end":"2026-09-07T19:13:28.396254Z","steps":["trace[2036663641] 'process raft request' (duration: 90.054378ms)","trace[2036663641] 'compare' (duration: 25.900342ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:28.842377Z","caller":"traceutil/trace.go:171","msg":"trace[367548498] transaction","detail":"{read_only:false; response_revision:26409; number_of_response:1; }","duration":"108.945702ms","start":"2026-09-07T19:13:28.733389Z","end":"2026-09-07T19:13:28.842335Z","steps":["trace[367548498] 'process raft request' (duration: 108.824909ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:28.842492Z","caller":"traceutil/trace.go:171","msg":"trace[52515271] transaction","detail":"{read_only:false; response_revision:26408; number_of_response:1; }","duration":"136.714192ms","start":"2026-09-07T19:13:28.705683Z","end":"2026-09-07T19:13:28.842398Z","steps":["trace[52515271] 'process raft request' (duration: 101.86615ms)","trace[52515271] 'compare' (duration: 34.369602ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:28.842563Z","caller":"traceutil/trace.go:171","msg":"trace[1945968412] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"107.226295ms","start":"2026-09-07T19:13:28.735306Z","end":"2026-09-07T19:13:28.842533Z","steps":["trace[1945968412] 'process raft request' (duration: 106.975579ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:28.842952Z","caller":"traceutil/trace.go:171","msg":"trace[124505669] linearizableReadLoop","detail":"{readStateIndex:27285; appliedIndex:27283; }","duration":"109.735859ms","start":"2026-09-07T19:13:28.733183Z","end":"2026-09-07T19:13:28.842919Z","steps":["trace[124505669] 'read index received' (duration: 10.663378ms)","trace[124505669] 'applied index is now lower than readState.Index' (duration: 99.067631ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:28.843222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.006164ms","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:28.843291Z","caller":"traceutil/trace.go:171","msg":"trace[121870082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26410; }","duration":"110.168338ms","start":"2026-09-07T19:13:28.733103Z","end":"2026-09-07T19:13:28.843271Z","steps":["trace[121870082] 'agreement among raft nodes before linearized reading' (duration: 109.972444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:28.843222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.008344ms","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:28.843432Z","caller":"traceutil/trace.go:171","msg":"trace[1861810527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26410; }","duration":"110.2794ms","start":"2026-09-07T19:13:28.733103Z","end":"2026-09-07T19:13:28.843382Z","steps":["trace[1861810527] 'agreement among raft nodes before linearized reading' (duration: 109.889572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:28.843528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.093394ms","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:28.843781Z","caller":"traceutil/trace.go:171","msg":"trace[969070896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26410; }","duration":"109.421271ms","start":"2026-09-07T19:13:28.734341Z","end":"2026-09-07T19:13:28.843763Z","steps":["trace[969070896] 'agreement among raft nodes before linearized reading' (duration: 108.86207ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:29.148773Z","caller":"traceutil/trace.go:171","msg":"trace[2017823138] transaction","detail":"{read_only:false; response_revision:26414; number_of_response:1; }","duration":"111.799112ms","start":"2026-09-07T19:13:29.036943Z","end":"2026-09-07T19:13:29.148742Z","steps":["trace[2017823138] 'process raft request' (duration: 111.611548ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:30.529765Z","caller":"traceutil/trace.go:171","msg":"trace[337173028] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"194.824302ms","start":"2026-09-07T19:13:30.331713Z","end":"2026-09-07T19:13:30.526537Z","steps":["trace[337173028] 'process raft request' (duration: 194.476343ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:30.568209Z","caller":"traceutil/trace.go:171","msg":"trace[1409489903] linearizableReadLoop","detail":"{readStateIndex:27298; appliedIndex:27296; }","duration":"117.240217ms","start":"2026-09-07T19:13:30.45094Z","end":"2026-09-07T19:13:30.56818Z","steps":["trace[1409489903] 'read index received' (duration: 75.278233ms)","trace[1409489903] 'applied index is now lower than readState.Index' (duration: 41.960584ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:30.568285Z","caller":"traceutil/trace.go:171","msg":"trace[1281096877] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"163.82379ms","start":"2026-09-07T19:13:30.404426Z","end":"2026-09-07T19:13:30.56825Z","steps":["trace[1281096877] 'process raft request' (duration: 163.573965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:30.568388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.408431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:30.568426Z","caller":"traceutil/trace.go:171","msg":"trace[921015393] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26421; }","duration":"117.522653ms","start":"2026-09-07T19:13:30.450889Z","end":"2026-09-07T19:13:30.568412Z","steps":["trace[921015393] 'agreement among raft nodes before linearized reading' (duration: 117.412781ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.000888Z","caller":"traceutil/trace.go:171","msg":"trace[1225874597] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"148.527165ms","start":"2026-09-07T19:13:30.852302Z","end":"2026-09-07T19:13:31.000829Z","steps":["trace[1225874597] 'process raft request' (duration: 120.848685ms)","trace[1225874597] 'compare' (duration: 27.454735ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:31.001257Z","caller":"traceutil/trace.go:171","msg":"trace[1106759946] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"148.79698ms","start":"2026-09-07T19:13:30.85244Z","end":"2026-09-07T19:13:31.001237Z","steps":["trace[1106759946] 'process raft request' (duration: 148.34074ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.00122Z","caller":"traceutil/trace.go:171","msg":"trace[1116771616] linearizableReadLoop","detail":"{readStateIndex:27301; appliedIndex:27299; }","duration":"111.6935ms","start":"2026-09-07T19:13:30.889437Z","end":"2026-09-07T19:13:31.001131Z","steps":["trace[1116771616] 'read index received' (duration: 83.600281ms)","trace[1116771616] 'applied index is now lower than readState.Index' (duration: 28.090969ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:31.001575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.881838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:13:31.001691Z","caller":"traceutil/trace.go:171","msg":"trace[188137073] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26424; }","duration":"146.138553ms","start":"2026-09-07T19:13:30.855494Z","end":"2026-09-07T19:13:31.001632Z","steps":["trace[188137073] 'agreement among raft nodes before linearized reading' (duration: 145.819417ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.246453Z","caller":"traceutil/trace.go:171","msg":"trace[1562072914] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"240.79824ms","start":"2026-09-07T19:13:31.005623Z","end":"2026-09-07T19:13:31.246422Z","steps":["trace[1562072914] 'process raft request' (duration: 219.588908ms)","trace[1562072914] 'compare' (duration: 20.933546ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:31.470156Z","caller":"traceutil/trace.go:171","msg":"trace[1415244384] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"464.250962ms","start":"2026-09-07T19:13:31.005877Z","end":"2026-09-07T19:13:31.470128Z","steps":["trace[1415244384] 'process raft request' (duration: 388.816494ms)","trace[1415244384] 'compare' (duration: 75.198893ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:31.470329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:31.005839Z","time spent":"464.391235ms","remote":"127.0.0.1:54730","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:13:31.470626Z","caller":"traceutil/trace.go:171","msg":"trace[1196628604] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"463.527947ms","start":"2026-09-07T19:13:31.007081Z","end":"2026-09-07T19:13:31.470609Z","steps":["trace[1196628604] 'process raft request' (duration: 463.005295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.470679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:31.007066Z","time spent":"463.585289ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:13:31.470745Z","caller":"traceutil/trace.go:171","msg":"trace[1120981832] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"186.26341ms","start":"2026-09-07T19:13:31.284457Z","end":"2026-09-07T19:13:31.47072Z","steps":["trace[1120981832] 'process raft request' (duration: 186.215329ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.470868Z","caller":"traceutil/trace.go:171","msg":"trace[1506297568] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"303.208532ms","start":"2026-09-07T19:13:31.167647Z","end":"2026-09-07T19:13:31.470856Z","steps":["trace[1506297568] 'process raft request' (duration: 302.945536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.470913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:31.167634Z","time spent":"303.254322ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:13:31.470978Z","caller":"traceutil/trace.go:171","msg":"trace[35448427] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"315.564094ms","start":"2026-09-07T19:13:31.155404Z","end":"2026-09-07T19:13:31.470968Z","steps":["trace[35448427] 'process raft request' (duration: 315.070313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.471017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:31.155376Z","time spent":"315.620496ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:13:31.471013Z","caller":"traceutil/trace.go:171","msg":"trace[1499317832] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"291.507991ms","start":"2026-09-07T19:13:31.179492Z","end":"2026-09-07T19:13:31.471Z","steps":["trace[1499317832] 'process raft request' (duration: 291.142533ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.997596Z","caller":"traceutil/trace.go:171","msg":"trace[873897762] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"192.352629ms","start":"2026-09-07T19:13:31.805216Z","end":"2026-09-07T19:13:31.997569Z","steps":["trace[873897762] 'process raft request' (duration: 192.062543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.99773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.885479ms","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.997777Z","caller":"traceutil/trace.go:171","msg":"trace[1257144306] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26435; }","duration":"191.981021ms","start":"2026-09-07T19:13:31.805784Z","end":"2026-09-07T19:13:31.997765Z","steps":["trace[1257144306] 'agreement among raft nodes before linearized reading' (duration: 191.874559ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:31.997539Z","caller":"traceutil/trace.go:171","msg":"trace[1149618289] linearizableReadLoop","detail":"{readStateIndex:27312; appliedIndex:27311; }","duration":"191.691345ms","start":"2026-09-07T19:13:31.805813Z","end":"2026-09-07T19:13:31.997505Z","steps":["trace[1149618289] 'read index received' (duration: 191.563382ms)","trace[1149618289] 'applied index is now lower than readState.Index' (duration: 126.363µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:31.998152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.620583ms","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.998195Z","caller":"traceutil/trace.go:171","msg":"trace[1248962957] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26435; }","duration":"191.687045ms","start":"2026-09-07T19:13:31.806496Z","end":"2026-09-07T19:13:31.998183Z","steps":["trace[1248962957] 'agreement among raft nodes before linearized reading' (duration: 191.501711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:31.998183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.203986ms","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.998306Z","caller":"traceutil/trace.go:171","msg":"trace[438462892] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26435; }","duration":"192.448971ms","start":"2026-09-07T19:13:31.805834Z","end":"2026-09-07T19:13:31.998283Z","steps":["trace[438462892] 'agreement among raft nodes before linearized reading' (duration: 192.157785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.158434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.179744ms","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:33.158521Z","caller":"traceutil/trace.go:171","msg":"trace[2044861863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26437; }","duration":"260.326157ms","start":"2026-09-07T19:13:32.898172Z","end":"2026-09-07T19:13:33.158498Z","steps":["trace[2044861863] 'range keys from in-memory index tree' (duration: 259.890947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.158566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.782981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T19:13:33.158675Z","caller":"traceutil/trace.go:171","msg":"trace[1581585753] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:26437; }","duration":"332.985725ms","start":"2026-09-07T19:13:32.825665Z","end":"2026-09-07T19:13:33.15865Z","steps":["trace[1581585753] 'count revisions from in-memory index tree' (duration: 332.619927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.158742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:32.825634Z","time spent":"333.095138ms","remote":"127.0.0.1:54746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":91,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-09-07T19:13:33.158903Z","caller":"traceutil/trace.go:171","msg":"trace[637028986] transaction","detail":"{read_only:false; response_revision:26439; number_of_response:1; }","duration":"146.965561ms","start":"2026-09-07T19:13:33.011907Z","end":"2026-09-07T19:13:33.158873Z","steps":["trace[637028986] 'process raft request' (duration: 146.825228ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:33.158987Z","caller":"traceutil/trace.go:171","msg":"trace[1090150698] transaction","detail":"{read_only:false; response_revision:26438; number_of_response:1; }","duration":"150.809874ms","start":"2026-09-07T19:13:33.008155Z","end":"2026-09-07T19:13:33.158965Z","steps":["trace[1090150698] 'process raft request' (duration: 89.346804ms)","trace[1090150698] 'compare' (duration: 60.751795ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:33.463154Z","caller":"traceutil/trace.go:171","msg":"trace[1967997751] linearizableReadLoop","detail":"{readStateIndex:27318; appliedIndex:27317; }","duration":"210.883873ms","start":"2026-09-07T19:13:33.252236Z","end":"2026-09-07T19:13:33.463119Z","steps":["trace[1967997751] 'read index received' (duration: 210.481884ms)","trace[1967997751] 'applied index is now lower than readState.Index' (duration: 400.929µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:33.463216Z","caller":"traceutil/trace.go:171","msg":"trace[209348087] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"298.45067ms","start":"2026-09-07T19:13:33.164707Z","end":"2026-09-07T19:13:33.463158Z","steps":["trace[209348087] 'process raft request' (duration: 297.9812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.463361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.117899ms","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:33.463405Z","caller":"traceutil/trace.go:171","msg":"trace[792571623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26440; }","duration":"211.178411ms","start":"2026-09-07T19:13:33.252215Z","end":"2026-09-07T19:13:33.463393Z","steps":["trace[792571623] 'agreement among raft nodes before linearized reading' (duration: 211.020307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.636985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.145523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:33.637323Z","caller":"traceutil/trace.go:171","msg":"trace[1900677155] linearizableReadLoop","detail":"{readStateIndex:27320; appliedIndex:27318; }","duration":"160.05188ms","start":"2026-09-07T19:13:33.477252Z","end":"2026-09-07T19:13:33.637304Z","steps":["trace[1900677155] 'read index received' (duration: 44.548859ms)","trace[1900677155] 'applied index is now lower than readState.Index' (duration: 115.49899ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:33.637361Z","caller":"traceutil/trace.go:171","msg":"trace[1108968927] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"166.455486ms","start":"2026-09-07T19:13:33.470857Z","end":"2026-09-07T19:13:33.637312Z","steps":["trace[1108968927] 'process raft request' (duration: 50.888394ms)","trace[1108968927] 'compare' (duration: 114.862507ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:33.637383Z","caller":"traceutil/trace.go:171","msg":"trace[647766330] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"160.584741ms","start":"2026-09-07T19:13:33.476773Z","end":"2026-09-07T19:13:33.637358Z","steps":["trace[647766330] 'process raft request' (duration: 160.332425ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:33.63738Z","caller":"traceutil/trace.go:171","msg":"trace[20217363] transaction","detail":"{read_only:false; response_revision:26444; number_of_response:1; }","duration":"156.596216ms","start":"2026-09-07T19:13:33.480745Z","end":"2026-09-07T19:13:33.637341Z","steps":["trace[20217363] 'process raft request' (duration: 156.511855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.637524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.515646ms","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:33.63738Z","caller":"traceutil/trace.go:171","msg":"trace[908074433] transaction","detail":"{read_only:false; response_revision:26443; number_of_response:1; }","duration":"156.728919ms","start":"2026-09-07T19:13:33.480612Z","end":"2026-09-07T19:13:33.637341Z","steps":["trace[908074433] 'process raft request' (duration: 156.586846ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:33.637563Z","caller":"traceutil/trace.go:171","msg":"trace[864147126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26444; }","duration":"157.601028ms","start":"2026-09-07T19:13:33.479951Z","end":"2026-09-07T19:13:33.637552Z","steps":["trace[864147126] 'agreement among raft nodes before linearized reading' (duration: 157.464335ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.637673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.405408ms","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.63776Z","caller":"traceutil/trace.go:171","msg":"trace[433943301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26444; }","duration":"160.547491ms","start":"2026-09-07T19:13:33.477186Z","end":"2026-09-07T19:13:33.637734Z","steps":["trace[433943301] 'agreement among raft nodes before linearized reading' (duration: 160.192973ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:33.858636Z","caller":"traceutil/trace.go:171","msg":"trace[866977685] linearizableReadLoop","detail":"{readStateIndex:27324; appliedIndex:27322; }","duration":"214.651324ms","start":"2026-09-07T19:13:33.643958Z","end":"2026-09-07T19:13:33.858609Z","steps":["trace[866977685] 'read index received' (duration: 83.637512ms)","trace[866977685] 'applied index is now lower than readState.Index' (duration: 131.012702ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:33.859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.819151ms","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.859044Z","caller":"traceutil/trace.go:171","msg":"trace[272679229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26447; }","duration":"117.915253ms","start":"2026-09-07T19:13:33.741117Z","end":"2026-09-07T19:13:33.859032Z","steps":["trace[272679229] 'agreement among raft nodes before linearized reading' (duration: 117.833061ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:33.859109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.113043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19028"} {"level":"info","ts":"2026-09-07T19:13:33.859194Z","caller":"traceutil/trace.go:171","msg":"trace[891881378] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26447; }","duration":"215.256867ms","start":"2026-09-07T19:13:33.643918Z","end":"2026-09-07T19:13:33.859175Z","steps":["trace[891881378] 'agreement among raft nodes before linearized reading' (duration: 214.982861ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:33.859429Z","caller":"traceutil/trace.go:171","msg":"trace[1658031983] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"216.080534ms","start":"2026-09-07T19:13:33.643334Z","end":"2026-09-07T19:13:33.859414Z","steps":["trace[1658031983] 'process raft request' (duration: 188.946176ms)","trace[1658031983] 'compare' (duration: 26.078616ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:33.85943Z","caller":"traceutil/trace.go:171","msg":"trace[787470409] transaction","detail":"{read_only:false; response_revision:26447; number_of_response:1; }","duration":"156.100105ms","start":"2026-09-07T19:13:33.703314Z","end":"2026-09-07T19:13:33.859415Z","steps":["trace[787470409] 'process raft request' (duration: 155.212166ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:34.831954Z","caller":"traceutil/trace.go:171","msg":"trace[675591941] transaction","detail":"{read_only:false; response_revision:26451; number_of_response:1; }","duration":"107.820457ms","start":"2026-09-07T19:13:34.724088Z","end":"2026-09-07T19:13:34.831908Z","steps":["trace[675591941] 'process raft request' (duration: 107.462519ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:35.292104Z","caller":"traceutil/trace.go:171","msg":"trace[1306931812] transaction","detail":"{read_only:false; response_revision:26453; number_of_response:1; }","duration":"126.123957ms","start":"2026-09-07T19:13:35.165946Z","end":"2026-09-07T19:13:35.29207Z","steps":["trace[1306931812] 'process raft request' (duration: 99.805476ms)","trace[1306931812] 'compare' (duration: 26.186408ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:35.771854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.511328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:35.771973Z","caller":"traceutil/trace.go:171","msg":"trace[1409394408] linearizableReadLoop","detail":"{readStateIndex:27333; appliedIndex:27332; }","duration":"129.624182ms","start":"2026-09-07T19:13:35.642329Z","end":"2026-09-07T19:13:35.771953Z","steps":["trace[1409394408] 'read index received' (duration: 1.876529ms)","trace[1409394408] 'applied index is now lower than readState.Index' (duration: 127.746103ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:35.772059Z","caller":"traceutil/trace.go:171","msg":"trace[1237710942] transaction","detail":"{read_only:false; response_revision:26455; number_of_response:1; }","duration":"190.291655ms","start":"2026-09-07T19:13:35.58175Z","end":"2026-09-07T19:13:35.772042Z","steps":["trace[1237710942] 'process raft request' (duration: 62.4548ms)","trace[1237710942] 'compare' (duration: 127.1192ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:35.772385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.0297ms","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.772531Z","caller":"traceutil/trace.go:171","msg":"trace[2087841994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26455; }","duration":"130.189723ms","start":"2026-09-07T19:13:35.642276Z","end":"2026-09-07T19:13:35.772465Z","steps":["trace[2087841994] 'agreement among raft nodes before linearized reading' (duration: 129.921478ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:35.790115Z","caller":"traceutil/trace.go:171","msg":"trace[8657583] transaction","detail":"{read_only:false; response_revision:26456; number_of_response:1; }","duration":"146.848629ms","start":"2026-09-07T19:13:35.64325Z","end":"2026-09-07T19:13:35.790098Z","steps":["trace[8657583] 'process raft request' (duration: 146.667775ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:35.790498Z","caller":"traceutil/trace.go:171","msg":"trace[442579445] transaction","detail":"{read_only:false; response_revision:26458; number_of_response:1; }","duration":"146.956372ms","start":"2026-09-07T19:13:35.643529Z","end":"2026-09-07T19:13:35.790486Z","steps":["trace[442579445] 'process raft request' (duration: 146.803519ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:35.790701Z","caller":"traceutil/trace.go:171","msg":"trace[677930105] transaction","detail":"{read_only:false; response_revision:26457; number_of_response:1; }","duration":"147.34418ms","start":"2026-09-07T19:13:35.643347Z","end":"2026-09-07T19:13:35.790692Z","steps":["trace[677930105] 'process raft request' (duration: 146.685435ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:35.995Z","caller":"traceutil/trace.go:171","msg":"trace[1892705857] transaction","detail":"{read_only:false; response_revision:26461; number_of_response:1; }","duration":"129.641003ms","start":"2026-09-07T19:13:35.865305Z","end":"2026-09-07T19:13:35.994946Z","steps":["trace[1892705857] 'process raft request' (duration: 42.882855ms)","trace[1892705857] 'compare' (duration: 86.550533ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:36.021525Z","caller":"traceutil/trace.go:171","msg":"trace[347376566] linearizableReadLoop","detail":"{readStateIndex:27340; appliedIndex:27338; }","duration":"129.166912ms","start":"2026-09-07T19:13:35.89233Z","end":"2026-09-07T19:13:36.021497Z","steps":["trace[347376566] 'read index received' (duration: 15.882958ms)","trace[347376566] 'applied index is now lower than readState.Index' (duration: 113.282204ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:36.021602Z","caller":"traceutil/trace.go:171","msg":"trace[526567709] transaction","detail":"{read_only:false; response_revision:26462; number_of_response:1; }","duration":"149.344712ms","start":"2026-09-07T19:13:35.872222Z","end":"2026-09-07T19:13:36.021567Z","steps":["trace[526567709] 'process raft request' (duration: 149.084137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:36.021715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.314401ms","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:36.021764Z","caller":"traceutil/trace.go:171","msg":"trace[1250930547] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26462; }","duration":"110.407882ms","start":"2026-09-07T19:13:35.911337Z","end":"2026-09-07T19:13:36.021745Z","steps":["trace[1250930547] 'agreement among raft nodes before linearized reading' (duration: 110.31983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:36.021761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.238259ms","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:36.021812Z","caller":"traceutil/trace.go:171","msg":"trace[2031134004] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26462; }","duration":"110.368521ms","start":"2026-09-07T19:13:35.911431Z","end":"2026-09-07T19:13:36.021799Z","steps":["trace[2031134004] 'agreement among raft nodes before linearized reading' (duration: 110.269309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:36.021957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.632472ms","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:36.022074Z","caller":"traceutil/trace.go:171","msg":"trace[534177619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26462; }","duration":"129.709984ms","start":"2026-09-07T19:13:35.89227Z","end":"2026-09-07T19:13:36.02198Z","steps":["trace[534177619] 'agreement among raft nodes before linearized reading' (duration: 129.350886ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:36.515706Z","caller":"traceutil/trace.go:171","msg":"trace[1120693461] transaction","detail":"{read_only:false; response_revision:26464; number_of_response:1; }","duration":"116.372479ms","start":"2026-09-07T19:13:36.399304Z","end":"2026-09-07T19:13:36.515676Z","steps":["trace[1120693461] 'process raft request' (duration: 116.179125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:37.4261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.962592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:37.426254Z","caller":"traceutil/trace.go:171","msg":"trace[750010920] transaction","detail":"{read_only:false; response_revision:26469; number_of_response:1; }","duration":"127.888724ms","start":"2026-09-07T19:13:37.298338Z","end":"2026-09-07T19:13:37.426227Z","steps":["trace[750010920] 'process raft request' (duration: 25.635596ms)","trace[750010920] 'compare' (duration: 101.713106ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:37.898616Z","caller":"traceutil/trace.go:171","msg":"trace[37351795] transaction","detail":"{read_only:false; response_revision:26472; number_of_response:1; }","duration":"100.607804ms","start":"2026-09-07T19:13:37.797975Z","end":"2026-09-07T19:13:37.898583Z","steps":["trace[37351795] 'process raft request' (duration: 78.075924ms)","trace[37351795] 'compare' (duration: 22.359586ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:38.245044Z","caller":"traceutil/trace.go:171","msg":"trace[1096498104] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"165.67404ms","start":"2026-09-07T19:13:38.079338Z","end":"2026-09-07T19:13:38.245012Z","steps":["trace[1096498104] 'process raft request' (duration: 165.465105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:40.183948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.60781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:13:40.184097Z","caller":"traceutil/trace.go:171","msg":"trace[880258648] linearizableReadLoop","detail":"{readStateIndex:27367; appliedIndex:27366; }","duration":"105.731973ms","start":"2026-09-07T19:13:40.078328Z","end":"2026-09-07T19:13:40.18406Z","steps":["trace[880258648] 'read index received' (duration: 50.191µs)","trace[880258648] 'applied index is now lower than readState.Index' (duration: 105.680292ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:13:40.184195Z","caller":"traceutil/trace.go:171","msg":"trace[1741192219] transaction","detail":"{read_only:false; response_revision:26488; number_of_response:1; }","duration":"218.826672ms","start":"2026-09-07T19:13:39.965354Z","end":"2026-09-07T19:13:40.18418Z","steps":["trace[1741192219] 'process raft request' (duration: 97.822224ms)","trace[1741192219] 'compare' (duration: 120.458737ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:13:40.184363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.001128ms","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:40.184469Z","caller":"traceutil/trace.go:171","msg":"trace[263186174] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26488; }","duration":"106.201883ms","start":"2026-09-07T19:13:40.078241Z","end":"2026-09-07T19:13:40.184443Z","steps":["trace[263186174] 'agreement among raft nodes before linearized reading' (duration: 106.022299ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:40.205644Z","caller":"traceutil/trace.go:171","msg":"trace[1063177494] transaction","detail":"{read_only:false; response_revision:26489; number_of_response:1; }","duration":"125.400342ms","start":"2026-09-07T19:13:40.080188Z","end":"2026-09-07T19:13:40.205588Z","steps":["trace[1063177494] 'process raft request' (duration: 125.164247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:40.206676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.876293ms","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:40.20685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.18311ms","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:40.206902Z","caller":"traceutil/trace.go:171","msg":"trace[498820746] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26489; }","duration":"127.300372ms","start":"2026-09-07T19:13:40.079586Z","end":"2026-09-07T19:13:40.206887Z","steps":["trace[498820746] 'agreement among raft nodes before linearized reading' (duration: 127.17539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:40.206897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.192612ms","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:40.206971Z","caller":"traceutil/trace.go:171","msg":"trace[1182159351] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26489; }","duration":"128.297604ms","start":"2026-09-07T19:13:40.078655Z","end":"2026-09-07T19:13:40.206953Z","steps":["trace[1182159351] 'agreement among raft nodes before linearized reading' (duration: 128.189201ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:13:40.206915Z","caller":"traceutil/trace.go:171","msg":"trace[880643680] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26489; }","duration":"127.154249ms","start":"2026-09-07T19:13:40.079715Z","end":"2026-09-07T19:13:40.206869Z","steps":["trace[880643680] 'agreement among raft nodes before linearized reading' (duration: 126.844932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:40.20714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.051406ms","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:40.207186Z","caller":"traceutil/trace.go:171","msg":"trace[1591126252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26489; }","duration":"119.119137ms","start":"2026-09-07T19:13:40.088054Z","end":"2026-09-07T19:13:40.207173Z","steps":["trace[1591126252] 'agreement among raft nodes before linearized reading' (duration: 118.935843ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:13:40.207241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.508126ms","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:40.207271Z","caller":"traceutil/trace.go:171","msg":"trace[1283052273] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26489; }","duration":"127.584758ms","start":"2026-09-07T19:13:40.07968Z","end":"2026-09-07T19:13:40.207264Z","steps":["trace[1283052273] 'agreement among raft nodes before linearized reading' (duration: 127.433945ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:14:13.48806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23602} {"level":"info","ts":"2026-09-07T19:14:13.645701Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23602,"took":"151.299804ms","hash":2317562119,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":43315200,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-07T19:14:13.645798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2317562119,"revision":23602,"compact-revision":21384} {"level":"info","ts":"2026-09-07T19:19:13.500162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26736} {"level":"info","ts":"2026-09-07T19:19:13.652261Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26736,"took":"146.928191ms","hash":1628474485,"current-db-size-bytes":54960128,"current-db-size":"55 MB","current-db-size-in-use-bytes":30339072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T19:19:13.652364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1628474485,"revision":26736,"compact-revision":23602} {"level":"info","ts":"2026-09-07T19:19:24.930954Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0f50f8a6c5f7d06","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T19:19:24.937573Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-07T19:19:24.938208Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}