{"level":"warn","ts":"2026-10-26T21:25:43.753784Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T21:25:43.753951Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.4: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.4:2380","--initial-cluster=instance=https://162.253.55.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.4:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-26T21:25:43.754129Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T21:25:43.75416Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.4:2380"]} {"level":"info","ts":"2026-10-26T21:25:43.754201Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-26T21:25:43.755717Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"]} {"level":"info","ts":"2026-10-26T21:25:43.755903Z","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.4:2380"],"listen-peer-urls":["https://162.253.55.4:2380"],"advertise-client-urls":["https://162.253.55.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.4:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-26T21:25:43.75997Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.635543ms"} {"level":"info","ts":"2026-10-26T21:25:43.767827Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"caafea9f270da559","cluster-id":"271ff399bb4addea"} {"level":"info","ts":"2026-10-26T21:25:43.768016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T21:25:43.768087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became follower at term 0"} {"level":"info","ts":"2026-10-26T21:25:43.768124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft caafea9f270da559 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T21:25:43.768139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became follower at term 1"} {"level":"info","ts":"2026-10-26T21:25:43.768261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=(14605150085861713241)"} {"level":"warn","ts":"2026-10-26T21:25:43.774711Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T21:25:43.776759Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T21:25:43.778772Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-26T21:25:43.783169Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"caafea9f270da559","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T21:25:43.783844Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"caafea9f270da559","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T21:25:43.783996Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T21:25:43.784159Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T21:25:43.784184Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T21:25:43.786479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=(14605150085861713241)"} {"level":"info","ts":"2026-10-26T21:25:43.786872Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"271ff399bb4addea","local-member-id":"caafea9f270da559","added-peer-id":"caafea9f270da559","added-peer-peer-urls":["https://162.253.55.4:2380"]} {"level":"info","ts":"2026-10-26T21:25:43.789781Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-26T21:25:43.789921Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.4:2380"} {"level":"info","ts":"2026-10-26T21:25:43.789951Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.4:2380"} {"level":"info","ts":"2026-10-26T21:25:43.790152Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"caafea9f270da559","initial-advertise-peer-urls":["https://162.253.55.4:2380"],"listen-peer-urls":["https://162.253.55.4:2380"],"advertise-client-urls":["https://162.253.55.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T21:25:43.790194Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T21:25:43.971638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T21:25:43.971734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T21:25:43.971765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 received MsgPreVoteResp from caafea9f270da559 at term 1"} {"level":"info","ts":"2026-10-26T21:25:43.971785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became candidate at term 2"} {"level":"info","ts":"2026-10-26T21:25:43.971792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 received MsgVoteResp from caafea9f270da559 at term 2"} {"level":"info","ts":"2026-10-26T21:25:43.971803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became leader at term 2"} {"level":"info","ts":"2026-10-26T21:25:43.971814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: caafea9f270da559 elected leader caafea9f270da559 at term 2"} {"level":"info","ts":"2026-10-26T21:25:43.97987Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"caafea9f270da559","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.4:2379]}","request-path":"/0/members/caafea9f270da559/attributes","cluster-id":"271ff399bb4addea","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T21:25:43.979989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T21:25:43.97995Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:25:43.980054Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T21:25:43.980917Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"271ff399bb4addea","local-member-id":"caafea9f270da559","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:25:43.981063Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:25:43.981099Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:25:43.983702Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T21:25:43.983739Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T21:25:43.983873Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T21:25:43.983983Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.4:2379"} {"level":"warn","ts":"2026-10-26T21:26:03.605946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.772055ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-26T21:26:03.606104Z","caller":"traceutil/trace.go:171","msg":"trace[1910014704] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"142.301394ms","start":"2026-10-26T21:26:03.463781Z","end":"2026-10-26T21:26:03.606082Z","steps":["trace[1910014704] 'read index received' (duration: 49.091µs)","trace[1910014704] 'applied index is now lower than readState.Index' (duration: 142.250163ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:26:03.606238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.441888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T21:26:03.60628Z","caller":"traceutil/trace.go:171","msg":"trace[924298251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:378; }","duration":"142.515169ms","start":"2026-10-26T21:26:03.463748Z","end":"2026-10-26T21:26:03.606263Z","steps":["trace[924298251] 'agreement among raft nodes before linearized reading' (duration: 142.402047ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:26:36.155115Z","caller":"traceutil/trace.go:171","msg":"trace[34529123] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"303.698239ms","start":"2026-10-26T21:26:35.851339Z","end":"2026-10-26T21:26:36.155037Z","steps":["trace[34529123] 'read index received' (duration: 297.06748ms)","trace[34529123] 'applied index is now lower than readState.Index' (duration: 6.629539ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:26:36.155218Z","caller":"traceutil/trace.go:171","msg":"trace[901079820] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"328.280482ms","start":"2026-10-26T21:26:35.826886Z","end":"2026-10-26T21:26:36.155166Z","steps":["trace[901079820] 'process raft request' (duration: 322.015362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:26:36.155448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.103988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-26T21:26:36.15549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.669908ms","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-10-26T21:26:36.155519Z","caller":"traceutil/trace.go:171","msg":"trace[490685833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"304.215771ms","start":"2026-10-26T21:26:35.851283Z","end":"2026-10-26T21:26:36.155498Z","steps":["trace[490685833] 'agreement among raft nodes before linearized reading' (duration: 304.003906ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:26:36.155555Z","caller":"traceutil/trace.go:171","msg":"trace[611151255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"154.997855ms","start":"2026-10-26T21:26:36.000523Z","end":"2026-10-26T21:26:36.155521Z","steps":["trace[611151255] 'agreement among raft nodes before linearized reading' (duration: 154.793991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:26:36.15562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:26:35.851263Z","time spent":"304.345733ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T21:26:36.156142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:26:35.826853Z","time spent":"328.512387ms","remote":"127.0.0.1:48962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036233,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:26:36.65608Z","caller":"traceutil/trace.go:171","msg":"trace[1820197871] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"429.211532ms","start":"2026-10-26T21:26:36.226835Z","end":"2026-10-26T21:26:36.656047Z","steps":["trace[1820197871] 'process raft request' (duration: 387.409891ms)","trace[1820197871] 'compare' (duration: 41.430982ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:26:36.656054Z","caller":"traceutil/trace.go:171","msg":"trace[550767658] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"263.890854ms","start":"2026-10-26T21:26:36.392128Z","end":"2026-10-26T21:26:36.656018Z","steps":["trace[550767658] 'read index received' (duration: 222.053533ms)","trace[550767658] 'applied index is now lower than readState.Index' (duration: 41.835121ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:26:36.656213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:26:36.226808Z","time spent":"429.343445ms","remote":"127.0.0.1:49230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T21:26:36.65631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.16451ms","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-10-26T21:26:36.656385Z","caller":"traceutil/trace.go:171","msg":"trace[414108382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"264.305024ms","start":"2026-10-26T21:26:36.392061Z","end":"2026-10-26T21:26:36.656366Z","steps":["trace[414108382] 'agreement among raft nodes before linearized reading' (duration: 264.064448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:26:36.656789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.888515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T21:26:36.657028Z","caller":"traceutil/trace.go:171","msg":"trace[1194961252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"192.338035ms","start":"2026-10-26T21:26:36.464671Z","end":"2026-10-26T21:26:36.657009Z","steps":["trace[1194961252] 'agreement among raft nodes before linearized reading' (duration: 191.65496ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:10.56224Z","caller":"traceutil/trace.go:171","msg":"trace[1661413930] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"329.173157ms","start":"2026-10-26T21:28:10.233042Z","end":"2026-10-26T21:28:10.562215Z","steps":["trace[1661413930] 'process raft request' (duration: 329.019343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:10.563299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:10.23301Z","time spent":"330.13968ms","remote":"127.0.0.1:49050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3730,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:28:10.687743Z","caller":"traceutil/trace.go:171","msg":"trace[1419953288] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"117.434784ms","start":"2026-10-26T21:28:10.570277Z","end":"2026-10-26T21:28:10.687711Z","steps":["trace[1419953288] 'read index received' (duration: 117.133617ms)","trace[1419953288] 'applied index is now lower than readState.Index' (duration: 299.647µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:28:10.687967Z","caller":"traceutil/trace.go:171","msg":"trace[664842528] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"451.445181ms","start":"2026-10-26T21:28:10.236491Z","end":"2026-10-26T21:28:10.687936Z","steps":["trace[664842528] 'process raft request' (duration: 450.915259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:10.688134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:28:10.236473Z","time spent":"451.567294ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:28:10.68844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.15225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:11249"} {"level":"info","ts":"2026-10-26T21:28:10.688495Z","caller":"traceutil/trace.go:171","msg":"trace[1249048639] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1862; }","duration":"118.255333ms","start":"2026-10-26T21:28:10.570225Z","end":"2026-10-26T21:28:10.68848Z","steps":["trace[1249048639] 'agreement among raft nodes before linearized reading' (duration: 117.601418ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:10.937867Z","caller":"traceutil/trace.go:171","msg":"trace[1810143071] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"216.349308ms","start":"2026-10-26T21:28:10.721491Z","end":"2026-10-26T21:28:10.93784Z","steps":["trace[1810143071] 'process raft request' (duration: 216.170504ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:10.945806Z","caller":"traceutil/trace.go:171","msg":"trace[1039003903] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"223.687835ms","start":"2026-10-26T21:28:10.722084Z","end":"2026-10-26T21:28:10.945772Z","steps":["trace[1039003903] 'process raft request' (duration: 223.342737ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:10.945929Z","caller":"traceutil/trace.go:171","msg":"trace[397204783] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1935; }","duration":"212.384287ms","start":"2026-10-26T21:28:10.733519Z","end":"2026-10-26T21:28:10.945903Z","steps":["trace[397204783] 'read index received' (duration: 204.283913ms)","trace[397204783] 'applied index is now lower than readState.Index' (duration: 8.099164ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:28:10.94617Z","caller":"traceutil/trace.go:171","msg":"trace[1292087244] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"223.235933ms","start":"2026-10-26T21:28:10.722912Z","end":"2026-10-26T21:28:10.946148Z","steps":["trace[1292087244] 'process raft request' (duration: 222.790833ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:10.94626Z","caller":"traceutil/trace.go:171","msg":"trace[1226058984] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"223.417717ms","start":"2026-10-26T21:28:10.722779Z","end":"2026-10-26T21:28:10.946197Z","steps":["trace[1226058984] 'process raft request' (duration: 222.835174ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:10.946434Z","caller":"traceutil/trace.go:171","msg":"trace[1163159288] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"222.60634ms","start":"2026-10-26T21:28:10.723816Z","end":"2026-10-26T21:28:10.946422Z","steps":["trace[1163159288] 'process raft request' (duration: 222.029197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:10.946388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.845328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T21:28:10.946431Z","caller":"traceutil/trace.go:171","msg":"trace[1943824860] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"223.203482ms","start":"2026-10-26T21:28:10.723133Z","end":"2026-10-26T21:28:10.946336Z","steps":["trace[1943824860] 'process raft request' (duration: 222.65115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:10.946531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.740319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T21:28:10.946638Z","caller":"traceutil/trace.go:171","msg":"trace[1455836987] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1869; }","duration":"135.884663ms","start":"2026-10-26T21:28:10.810724Z","end":"2026-10-26T21:28:10.946609Z","steps":["trace[1455836987] 'agreement among raft nodes before linearized reading' (duration: 135.632026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:10.946561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.128883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:28:10.94693Z","caller":"traceutil/trace.go:171","msg":"trace[1975122332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1869; }","duration":"213.050964ms","start":"2026-10-26T21:28:10.733471Z","end":"2026-10-26T21:28:10.946522Z","steps":["trace[1975122332] 'agreement among raft nodes before linearized reading' (duration: 212.784717ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:10.947Z","caller":"traceutil/trace.go:171","msg":"trace[1357308023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1869; }","duration":"175.698596ms","start":"2026-10-26T21:28:10.771274Z","end":"2026-10-26T21:28:10.946972Z","steps":["trace[1357308023] 'agreement among raft nodes before linearized reading' (duration: 175.088241ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:23.750493Z","caller":"traceutil/trace.go:171","msg":"trace[1274726324] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"142.098614ms","start":"2026-10-26T21:28:23.608368Z","end":"2026-10-26T21:28:23.750466Z","steps":["trace[1274726324] 'read index received' (duration: 134.268126ms)","trace[1274726324] 'applied index is now lower than readState.Index' (duration: 7.828898ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:28:23.750608Z","caller":"traceutil/trace.go:171","msg":"trace[703995969] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"262.863084ms","start":"2026-10-26T21:28:23.4877Z","end":"2026-10-26T21:28:23.750563Z","steps":["trace[703995969] 'process raft request' (duration: 254.942554ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:23.750681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.290648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:28:23.751471Z","caller":"traceutil/trace.go:171","msg":"trace[1367418713] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1974; }","duration":"143.102376ms","start":"2026-10-26T21:28:23.608346Z","end":"2026-10-26T21:28:23.751449Z","steps":["trace[1367418713] 'agreement among raft nodes before linearized reading' (duration: 142.235546ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:01.695086Z","caller":"traceutil/trace.go:171","msg":"trace[779853671] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"165.208228ms","start":"2026-10-26T21:29:01.529853Z","end":"2026-10-26T21:29:01.695061Z","steps":["trace[779853671] 'process raft request' (duration: 165.009863ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:02.012774Z","caller":"traceutil/trace.go:171","msg":"trace[1794543540] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"101.069108ms","start":"2026-10-26T21:29:01.911673Z","end":"2026-10-26T21:29:02.012742Z","steps":["trace[1794543540] 'process raft request' (duration: 100.832142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:53.708055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.43035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T21:29:53.708136Z","caller":"traceutil/trace.go:171","msg":"trace[1505292411] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2645; }","duration":"153.593043ms","start":"2026-10-26T21:29:53.554527Z","end":"2026-10-26T21:29:53.70812Z","steps":["trace[1505292411] 'count revisions from in-memory index tree' (duration: 153.338488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:10.759752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.121975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11932b33-3f24-41e5-9a66-f7dd90744af4\" ","response":"range_response_count:1 size:3599"} {"level":"info","ts":"2026-10-26T21:35:10.759855Z","caller":"traceutil/trace.go:171","msg":"trace[1489556223] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11932b33-3f24-41e5-9a66-f7dd90744af4; range_end:; response_count:1; response_revision:5294; }","duration":"242.261018ms","start":"2026-10-26T21:35:10.517561Z","end":"2026-10-26T21:35:10.759822Z","steps":["trace[1489556223] 'range keys from in-memory index tree' (duration: 241.919871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:10.759949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.059217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11932b33-3f24-41e5-9a66-f7dd90744af4\" ","response":"range_response_count:1 size:3599"} {"level":"info","ts":"2026-10-26T21:35:10.760034Z","caller":"traceutil/trace.go:171","msg":"trace[1908044763] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11932b33-3f24-41e5-9a66-f7dd90744af4; range_end:; response_count:1; response_revision:5294; }","duration":"125.188661ms","start":"2026-10-26T21:35:10.634827Z","end":"2026-10-26T21:35:10.760015Z","steps":["trace[1908044763] 'range keys from in-memory index tree' (duration: 124.914694ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:10.833958Z","caller":"traceutil/trace.go:171","msg":"trace[1691584035] linearizableReadLoop","detail":"{readStateIndex:5490; appliedIndex:5489; }","duration":"141.715765ms","start":"2026-10-26T21:35:10.692219Z","end":"2026-10-26T21:35:10.833935Z","steps":["trace[1691584035] 'read index received' (duration: 141.287185ms)","trace[1691584035] 'applied index is now lower than readState.Index' (duration: 425.499µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:35:10.834036Z","caller":"traceutil/trace.go:171","msg":"trace[1475624697] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"169.103774ms","start":"2026-10-26T21:35:10.664893Z","end":"2026-10-26T21:35:10.833997Z","steps":["trace[1475624697] 'process raft request' (duration: 168.710335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:10.834127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.899749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:35:10.834446Z","caller":"traceutil/trace.go:171","msg":"trace[375247543] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5295; }","duration":"142.275368ms","start":"2026-10-26T21:35:10.692158Z","end":"2026-10-26T21:35:10.834433Z","steps":["trace[375247543] 'agreement among raft nodes before linearized reading' (duration: 141.880469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:11.215015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.288926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:35:11.215287Z","caller":"traceutil/trace.go:171","msg":"trace[1343074069] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"112.242539ms","start":"2026-10-26T21:35:11.103026Z","end":"2026-10-26T21:35:11.215268Z","steps":["trace[1343074069] 'process raft request' (duration: 112.100386ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:11.215325Z","caller":"traceutil/trace.go:171","msg":"trace[456152252] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"259.340335ms","start":"2026-10-26T21:35:10.955955Z","end":"2026-10-26T21:35:11.215296Z","steps":["trace[456152252] 'process raft request' (duration: 113.688252ms)","trace[456152252] 'compare' (duration: 145.007069ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:35:24.829466Z","caller":"traceutil/trace.go:171","msg":"trace[950530786] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"102.369085ms","start":"2026-10-26T21:35:24.727065Z","end":"2026-10-26T21:35:24.829434Z","steps":["trace[950530786] 'process raft request' (duration: 66.29842ms)","trace[950530786] 'compare' (duration: 35.84063ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:35:24.829496Z","caller":"traceutil/trace.go:171","msg":"trace[1043193399] transaction","detail":"{read_only:false; response_revision:5565; number_of_response:1; }","duration":"101.650079ms","start":"2026-10-26T21:35:24.727819Z","end":"2026-10-26T21:35:24.829469Z","steps":["trace[1043193399] 'process raft request' (duration: 101.541387ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:25.497601Z","caller":"traceutil/trace.go:171","msg":"trace[2071361494] transaction","detail":"{read_only:false; response_revision:5567; number_of_response:1; }","duration":"233.931129ms","start":"2026-10-26T21:35:25.26365Z","end":"2026-10-26T21:35:25.497582Z","steps":["trace[2071361494] 'process raft request' (duration: 233.681894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:25.497903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.479304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T21:35:25.497932Z","caller":"traceutil/trace.go:171","msg":"trace[1205771925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5567; }","duration":"185.551565ms","start":"2026-10-26T21:35:25.312371Z","end":"2026-10-26T21:35:25.497922Z","steps":["trace[1205771925] 'agreement among raft nodes before linearized reading' (duration: 185.440603ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:25.497997Z","caller":"traceutil/trace.go:171","msg":"trace[1284419074] linearizableReadLoop","detail":"{readStateIndex:5772; appliedIndex:5771; }","duration":"185.096495ms","start":"2026-10-26T21:35:25.312422Z","end":"2026-10-26T21:35:25.497518Z","steps":["trace[1284419074] 'read index received' (duration: 184.959522ms)","trace[1284419074] 'applied index is now lower than readState.Index' (duration: 135.973µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:35:25.584722Z","caller":"traceutil/trace.go:171","msg":"trace[1119505206] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"191.904029ms","start":"2026-10-26T21:35:25.392779Z","end":"2026-10-26T21:35:25.584683Z","steps":["trace[1119505206] 'process raft request' (duration: 191.725805ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:25.584962Z","caller":"traceutil/trace.go:171","msg":"trace[1572108920] transaction","detail":"{read_only:false; response_revision:5569; number_of_response:1; }","duration":"107.719086ms","start":"2026-10-26T21:35:25.477216Z","end":"2026-10-26T21:35:25.584935Z","steps":["trace[1572108920] 'process raft request' (duration: 107.428079ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:25.585008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.435665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-26T21:35:25.585061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.382904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-brfns\" ","response":"range_response_count:1 size:6523"} {"level":"warn","ts":"2026-10-26T21:35:25.585061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.499319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T21:35:25.585099Z","caller":"traceutil/trace.go:171","msg":"trace[2074842626] range","detail":"{range_begin:/registry/pods/openstack/keepalived-brfns; range_end:; response_count:1; response_revision:5569; }","duration":"266.439546ms","start":"2026-10-26T21:35:25.318648Z","end":"2026-10-26T21:35:25.585088Z","steps":["trace[2074842626] 'agreement among raft nodes before linearized reading' (duration: 266.316722ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:25.585109Z","caller":"traceutil/trace.go:171","msg":"trace[886422469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5569; }","duration":"103.574152ms","start":"2026-10-26T21:35:25.481522Z","end":"2026-10-26T21:35:25.585096Z","steps":["trace[886422469] 'agreement among raft nodes before linearized reading' (duration: 103.455249ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:25.585064Z","caller":"traceutil/trace.go:171","msg":"trace[382022553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5569; }","duration":"117.532298ms","start":"2026-10-26T21:35:25.467516Z","end":"2026-10-26T21:35:25.585049Z","steps":["trace[382022553] 'agreement among raft nodes before linearized reading' (duration: 117.384684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:25.585164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.066142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T21:35:25.585235Z","caller":"traceutil/trace.go:171","msg":"trace[2133363488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5569; }","duration":"268.148964ms","start":"2026-10-26T21:35:25.317069Z","end":"2026-10-26T21:35:25.585218Z","steps":["trace[2133363488] 'agreement among raft nodes before linearized reading' (duration: 267.933768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:25.585383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.385099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-26T21:35:25.585431Z","caller":"traceutil/trace.go:171","msg":"trace[1083956307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5569; }","duration":"268.468471ms","start":"2026-10-26T21:35:25.31695Z","end":"2026-10-26T21:35:25.585418Z","steps":["trace[1083956307] 'agreement among raft nodes before linearized reading' (duration: 267.900178ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:33.7473Z","caller":"traceutil/trace.go:171","msg":"trace[1260310820] linearizableReadLoop","detail":"{readStateIndex:5958; appliedIndex:5957; }","duration":"119.932252ms","start":"2026-10-26T21:35:33.627335Z","end":"2026-10-26T21:35:33.747268Z","steps":["trace[1260310820] 'read index received' (duration: 30.857187ms)","trace[1260310820] 'applied index is now lower than readState.Index' (duration: 89.073655ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:35:33.747379Z","caller":"traceutil/trace.go:171","msg":"trace[1170994469] transaction","detail":"{read_only:false; response_revision:5748; number_of_response:1; }","duration":"128.909854ms","start":"2026-10-26T21:35:33.618436Z","end":"2026-10-26T21:35:33.747346Z","steps":["trace[1170994469] 'process raft request' (duration: 39.83139ms)","trace[1170994469] 'compare' (duration: 88.87124ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:35:33.74755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.191748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T21:35:33.747657Z","caller":"traceutil/trace.go:171","msg":"trace[938804278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5748; }","duration":"120.355302ms","start":"2026-10-26T21:35:33.627284Z","end":"2026-10-26T21:35:33.747639Z","steps":["trace[938804278] 'agreement among raft nodes before linearized reading' (duration: 120.091146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:33.747786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.32138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-26T21:35:33.747931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.502114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-26T21:35:33.74797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.086109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-brfns\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-26T21:35:33.747986Z","caller":"traceutil/trace.go:171","msg":"trace[920705912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5748; }","duration":"112.576225ms","start":"2026-10-26T21:35:33.635394Z","end":"2026-10-26T21:35:33.74797Z","steps":["trace[920705912] 'agreement among raft nodes before linearized reading' (duration: 112.289029ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:33.748Z","caller":"traceutil/trace.go:171","msg":"trace[864259845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5748; }","duration":"112.564316ms","start":"2026-10-26T21:35:33.635421Z","end":"2026-10-26T21:35:33.747985Z","steps":["trace[864259845] 'agreement among raft nodes before linearized reading' (duration: 112.230928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:33.747807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.56109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T21:35:33.748034Z","caller":"traceutil/trace.go:171","msg":"trace[1919103734] range","detail":"{range_begin:/registry/pods/openstack/keepalived-brfns; range_end:; response_count:1; response_revision:5748; }","duration":"114.168971ms","start":"2026-10-26T21:35:33.633845Z","end":"2026-10-26T21:35:33.748014Z","steps":["trace[1919103734] 'agreement among raft nodes before linearized reading' (duration: 114.014368ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:33.748064Z","caller":"traceutil/trace.go:171","msg":"trace[2044228190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5748; }","duration":"114.871977ms","start":"2026-10-26T21:35:33.633181Z","end":"2026-10-26T21:35:33.748053Z","steps":["trace[2044228190] 'agreement among raft nodes before linearized reading' (duration: 114.367396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:35:33.74816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.640814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-26T21:35:33.748291Z","caller":"traceutil/trace.go:171","msg":"trace[1529143135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5748; }","duration":"111.813099ms","start":"2026-10-26T21:35:33.636453Z","end":"2026-10-26T21:35:33.748266Z","steps":["trace[1529143135] 'agreement among raft nodes before linearized reading' (duration: 111.45937ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:35:44.382021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2944} {"level":"info","ts":"2026-10-26T21:35:44.459002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2944,"took":"75.529458ms","hash":4142829719,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":31965184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T21:35:44.459092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4142829719,"revision":2944,"compact-revision":-1} {"level":"info","ts":"2026-10-26T21:40:44.38985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5869} {"level":"info","ts":"2026-10-26T21:40:44.496818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5869,"took":"104.392177ms","hash":3424163100,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":26161152,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T21:40:44.496923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3424163100,"revision":5869,"compact-revision":2944} {"level":"info","ts":"2026-10-26T21:41:56.196416Z","caller":"traceutil/trace.go:171","msg":"trace[1828890499] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"374.966566ms","start":"2026-10-26T21:41:55.821424Z","end":"2026-10-26T21:41:56.196391Z","steps":["trace[1828890499] 'process raft request' (duration: 374.796973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:41:56.196593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:41:55.821391Z","time spent":"375.11052ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:41:56.199452Z","caller":"traceutil/trace.go:171","msg":"trace[18957159] transaction","detail":"{read_only:false; response_revision:8640; number_of_response:1; }","duration":"377.35537ms","start":"2026-10-26T21:41:55.822071Z","end":"2026-10-26T21:41:56.199426Z","steps":["trace[18957159] 'process raft request' (duration: 377.179236ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:41:56.19965Z","caller":"traceutil/trace.go:171","msg":"trace[1304124871] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"207.180039ms","start":"2026-10-26T21:41:55.992454Z","end":"2026-10-26T21:41:56.199634Z","steps":["trace[1304124871] 'process raft request' (duration: 206.928823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:41:56.199668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:41:55.822056Z","time spent":"377.470772ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:41:56.199771Z","caller":"traceutil/trace.go:171","msg":"trace[1404128398] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"159.205102ms","start":"2026-10-26T21:41:56.040535Z","end":"2026-10-26T21:41:56.199741Z","steps":["trace[1404128398] 'process raft request' (duration: 159.031578ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:41:56.199765Z","caller":"traceutil/trace.go:171","msg":"trace[1882551502] linearizableReadLoop","detail":"{readStateIndex:8943; appliedIndex:8941; }","duration":"353.007279ms","start":"2026-10-26T21:41:55.84673Z","end":"2026-10-26T21:41:56.199738Z","steps":["trace[1882551502] 'read index received' (duration: 349.570091ms)","trace[1882551502] 'applied index is now lower than readState.Index' (duration: 3.435808ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:41:56.199983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.240315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T21:41:56.200026Z","caller":"traceutil/trace.go:171","msg":"trace[2143873847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8642; }","duration":"353.319576ms","start":"2026-10-26T21:41:55.846694Z","end":"2026-10-26T21:41:56.200014Z","steps":["trace[2143873847] 'agreement among raft nodes before linearized reading' (duration: 353.147012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:41:56.200078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:41:55.846683Z","time spent":"353.384878ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T21:41:56.200085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.119982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-26T21:41:56.200122Z","caller":"traceutil/trace.go:171","msg":"trace[1422630238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8642; }","duration":"334.195104ms","start":"2026-10-26T21:41:55.865916Z","end":"2026-10-26T21:41:56.200111Z","steps":["trace[1422630238] 'agreement among raft nodes before linearized reading' (duration: 334.06482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:41:56.200149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:41:55.8659Z","time spent":"334.242206ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T21:41:56.200239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.322552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T21:41:56.20038Z","caller":"traceutil/trace.go:171","msg":"trace[75064580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8642; }","duration":"317.505317ms","start":"2026-10-26T21:41:55.88285Z","end":"2026-10-26T21:41:56.200355Z","steps":["trace[75064580] 'agreement among raft nodes before linearized reading' (duration: 317.20384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:41:56.200428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:41:55.882837Z","time spent":"317.581528ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T21:42:08.114862Z","caller":"traceutil/trace.go:171","msg":"trace[1716869939] transaction","detail":"{read_only:false; response_revision:8702; number_of_response:1; }","duration":"134.804131ms","start":"2026-10-26T21:42:07.980035Z","end":"2026-10-26T21:42:08.114839Z","steps":["trace[1716869939] 'process raft request' (duration: 134.625177ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:42:37.975167Z","caller":"traceutil/trace.go:171","msg":"trace[999093026] transaction","detail":"{read_only:false; response_revision:8917; number_of_response:1; }","duration":"104.545686ms","start":"2026-10-26T21:42:37.870592Z","end":"2026-10-26T21:42:37.975138Z","steps":["trace[999093026] 'process raft request' (duration: 104.312451ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:42:48.72167Z","caller":"traceutil/trace.go:171","msg":"trace[1554653137] transaction","detail":"{read_only:false; response_revision:8975; number_of_response:1; }","duration":"168.652927ms","start":"2026-10-26T21:42:48.552986Z","end":"2026-10-26T21:42:48.721639Z","steps":["trace[1554653137] 'process raft request' (duration: 124.503198ms)","trace[1554653137] 'compare' (duration: 43.669638ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:42:48.919402Z","caller":"traceutil/trace.go:171","msg":"trace[1629878946] transaction","detail":"{read_only:false; response_revision:8976; number_of_response:1; }","duration":"193.156711ms","start":"2026-10-26T21:42:48.726207Z","end":"2026-10-26T21:42:48.919363Z","steps":["trace[1629878946] 'process raft request' (duration: 192.941936ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:42:48.93649Z","caller":"traceutil/trace.go:171","msg":"trace[558355705] linearizableReadLoop","detail":"{readStateIndex:9294; appliedIndex:9292; }","duration":"207.603948ms","start":"2026-10-26T21:42:48.728856Z","end":"2026-10-26T21:42:48.93646Z","steps":["trace[558355705] 'read index received' (duration: 190.345968ms)","trace[558355705] 'applied index is now lower than readState.Index' (duration: 17.25637ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:42:48.936521Z","caller":"traceutil/trace.go:171","msg":"trace[358195324] transaction","detail":"{read_only:false; response_revision:8977; number_of_response:1; }","duration":"209.810409ms","start":"2026-10-26T21:42:48.726669Z","end":"2026-10-26T21:42:48.93648Z","steps":["trace[358195324] 'process raft request' (duration: 209.546893ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:42:48.936648Z","caller":"traceutil/trace.go:171","msg":"trace[1135605713] transaction","detail":"{read_only:false; response_revision:8978; number_of_response:1; }","duration":"153.879123ms","start":"2026-10-26T21:42:48.782749Z","end":"2026-10-26T21:42:48.936628Z","steps":["trace[1135605713] 'process raft request' (duration: 153.655518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:42:48.937293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.409886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:230180"} {"level":"info","ts":"2026-10-26T21:42:48.937336Z","caller":"traceutil/trace.go:171","msg":"trace[2025599919] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8978; }","duration":"208.474018ms","start":"2026-10-26T21:42:48.728851Z","end":"2026-10-26T21:42:48.937325Z","steps":["trace[2025599919] 'agreement among raft nodes before linearized reading' (duration: 208.002077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:42:48.937367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.350913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:42:48.937424Z","caller":"traceutil/trace.go:171","msg":"trace[846557188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8978; }","duration":"165.436185ms","start":"2026-10-26T21:42:48.771975Z","end":"2026-10-26T21:42:48.937411Z","steps":["trace[846557188] 'agreement among raft nodes before linearized reading' (duration: 165.314842ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:43:03.868967Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024e7.wal"} {"level":"info","ts":"2026-10-26T21:43:53.644929Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T21:43:53.652729Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T21:43:53.65304Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T21:44:05.886507Z","caller":"traceutil/trace.go:171","msg":"trace[354884725] transaction","detail":"{read_only:false; response_revision:9768; number_of_response:1; }","duration":"122.696782ms","start":"2026-10-26T21:44:05.763777Z","end":"2026-10-26T21:44:05.886474Z","steps":["trace[354884725] 'process raft request' (duration: 122.513878ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:44:20.454049Z","caller":"traceutil/trace.go:171","msg":"trace[1080356626] transaction","detail":"{read_only:false; response_revision:9850; number_of_response:1; }","duration":"192.839692ms","start":"2026-10-26T21:44:20.261177Z","end":"2026-10-26T21:44:20.454017Z","steps":["trace[1080356626] 'process raft request' (duration: 192.611426ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:45:44.399093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8031} {"level":"info","ts":"2026-10-26T21:45:44.472193Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8031,"took":"69.940453ms","hash":1380744647,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":27992064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T21:45:44.472263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1380744647,"revision":8031,"compact-revision":5869} {"level":"info","ts":"2026-10-26T21:50:03.444105Z","caller":"traceutil/trace.go:171","msg":"trace[195380312] transaction","detail":"{read_only:false; response_revision:12306; number_of_response:1; }","duration":"112.822431ms","start":"2026-10-26T21:50:03.331251Z","end":"2026-10-26T21:50:03.444073Z","steps":["trace[195380312] 'process raft request' (duration: 104.834591ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:50:03.486249Z","caller":"traceutil/trace.go:171","msg":"trace[926513959] linearizableReadLoop","detail":"{readStateIndex:12730; appliedIndex:12728; }","duration":"110.767936ms","start":"2026-10-26T21:50:03.375452Z","end":"2026-10-26T21:50:03.48622Z","steps":["trace[926513959] 'read index received' (duration: 60.717208ms)","trace[926513959] 'applied index is now lower than readState.Index' (duration: 50.049398ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:50:03.486377Z","caller":"traceutil/trace.go:171","msg":"trace[680692255] transaction","detail":"{read_only:false; response_revision:12307; number_of_response:1; }","duration":"151.052323ms","start":"2026-10-26T21:50:03.335313Z","end":"2026-10-26T21:50:03.486365Z","steps":["trace[680692255] 'process raft request' (duration: 150.720725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:50:03.486762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.297808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-26T21:50:03.486808Z","caller":"traceutil/trace.go:171","msg":"trace[2001607618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12307; }","duration":"111.41463ms","start":"2026-10-26T21:50:03.37538Z","end":"2026-10-26T21:50:03.486795Z","steps":["trace[2001607618] 'agreement among raft nodes before linearized reading' (duration: 111.247637ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:50:44.405212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10423} {"level":"info","ts":"2026-10-26T21:50:44.499099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10423,"took":"90.644822ms","hash":2277890971,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":25804800,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T21:50:44.499173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2277890971,"revision":10423,"compact-revision":8031} {"level":"info","ts":"2026-10-26T21:55:44.41718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12598} {"level":"info","ts":"2026-10-26T21:55:44.49898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12598,"took":"78.962194ms","hash":1304523418,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":27566080,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T21:55:44.499524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1304523418,"revision":12598,"compact-revision":10423} {"level":"info","ts":"2026-10-26T21:56:03.122805Z","caller":"traceutil/trace.go:171","msg":"trace[552075979] transaction","detail":"{read_only:false; response_revision:14747; number_of_response:1; }","duration":"153.741703ms","start":"2026-10-26T21:56:02.969033Z","end":"2026-10-26T21:56:03.122774Z","steps":["trace[552075979] 'process raft request' (duration: 72.467157ms)","trace[552075979] 'compare' (duration: 80.989699ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:56:03.125022Z","caller":"traceutil/trace.go:171","msg":"trace[1634045498] linearizableReadLoop","detail":"{readStateIndex:15256; appliedIndex:15254; }","duration":"135.773485ms","start":"2026-10-26T21:56:02.989224Z","end":"2026-10-26T21:56:03.124997Z","steps":["trace[1634045498] 'read index received' (duration: 52.283878ms)","trace[1634045498] 'applied index is now lower than readState.Index' (duration: 83.487555ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:56:03.125244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.030735ms","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-10-26T21:56:03.125303Z","caller":"traceutil/trace.go:171","msg":"trace[1419593601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14747; }","duration":"101.159879ms","start":"2026-10-26T21:56:03.024129Z","end":"2026-10-26T21:56:03.125289Z","steps":["trace[1419593601] 'agreement among raft nodes before linearized reading' (duration: 101.002986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:56:03.125245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.993769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T21:56:03.12558Z","caller":"traceutil/trace.go:171","msg":"trace[2084392921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14747; }","duration":"136.375948ms","start":"2026-10-26T21:56:02.989185Z","end":"2026-10-26T21:56:03.12556Z","steps":["trace[2084392921] 'agreement among raft nodes before linearized reading' (duration: 135.887607ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:56:46.623409Z","caller":"traceutil/trace.go:171","msg":"trace[1372581067] transaction","detail":"{read_only:false; response_revision:14990; number_of_response:1; }","duration":"121.711564ms","start":"2026-10-26T21:56:46.501655Z","end":"2026-10-26T21:56:46.623367Z","steps":["trace[1372581067] 'process raft request' (duration: 121.468109ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:57:11.682519Z","caller":"traceutil/trace.go:171","msg":"trace[1298625565] transaction","detail":"{read_only:false; response_revision:15316; number_of_response:1; }","duration":"115.81721ms","start":"2026-10-26T21:57:11.56666Z","end":"2026-10-26T21:57:11.682477Z","steps":["trace[1298625565] 'process raft request' (duration: 111.204796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:57:12.029048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.152127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b2ed63ce-3f29-4f36-9c8b-e86b1871a22f\" ","response":"range_response_count:1 size:3635"} {"level":"info","ts":"2026-10-26T21:57:12.029144Z","caller":"traceutil/trace.go:171","msg":"trace[787624164] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b2ed63ce-3f29-4f36-9c8b-e86b1871a22f; range_end:; response_count:1; response_revision:15316; }","duration":"145.310291ms","start":"2026-10-26T21:57:11.883809Z","end":"2026-10-26T21:57:12.029119Z","steps":["trace[787624164] 'range keys from in-memory index tree' (duration: 144.84201ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:57:12.148169Z","caller":"traceutil/trace.go:171","msg":"trace[11371648] transaction","detail":"{read_only:false; response_revision:15319; number_of_response:1; }","duration":"113.270543ms","start":"2026-10-26T21:57:12.034851Z","end":"2026-10-26T21:57:12.148122Z","steps":["trace[11371648] 'process raft request' (duration: 112.989436ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:57:12.148383Z","caller":"traceutil/trace.go:171","msg":"trace[3853488] transaction","detail":"{read_only:false; response_revision:15320; number_of_response:1; }","duration":"109.916357ms","start":"2026-10-26T21:57:12.038433Z","end":"2026-10-26T21:57:12.148349Z","steps":["trace[3853488] 'process raft request' (duration: 109.462056ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:57:12.148408Z","caller":"traceutil/trace.go:171","msg":"trace[1611472801] transaction","detail":"{read_only:false; response_revision:15318; number_of_response:1; }","duration":"113.699093ms","start":"2026-10-26T21:57:12.034607Z","end":"2026-10-26T21:57:12.148306Z","steps":["trace[1611472801] 'process raft request' (duration: 102.338185ms)","trace[1611472801] 'compare' (duration: 10.755554ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:58:27.752903Z","caller":"traceutil/trace.go:171","msg":"trace[955911086] transaction","detail":"{read_only:false; response_revision:16076; number_of_response:1; }","duration":"104.353981ms","start":"2026-10-26T21:58:27.648522Z","end":"2026-10-26T21:58:27.752876Z","steps":["trace[955911086] 'process raft request' (duration: 104.203677ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:58:27.931167Z","caller":"traceutil/trace.go:171","msg":"trace[1843383354] transaction","detail":"{read_only:false; response_revision:16077; number_of_response:1; }","duration":"139.890827ms","start":"2026-10-26T21:58:27.791227Z","end":"2026-10-26T21:58:27.931117Z","steps":["trace[1843383354] 'process raft request' (duration: 74.516542ms)","trace[1843383354] 'compare' (duration: 65.14955ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:58:28.547531Z","caller":"traceutil/trace.go:171","msg":"trace[497455497] transaction","detail":"{read_only:false; response_revision:16083; number_of_response:1; }","duration":"131.227831ms","start":"2026-10-26T21:58:28.41626Z","end":"2026-10-26T21:58:28.547488Z","steps":["trace[497455497] 'process raft request' (duration: 130.984355ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:58:28.700246Z","caller":"traceutil/trace.go:171","msg":"trace[1959405419] transaction","detail":"{read_only:false; response_revision:16084; number_of_response:1; }","duration":"103.345798ms","start":"2026-10-26T21:58:28.596862Z","end":"2026-10-26T21:58:28.700208Z","steps":["trace[1959405419] 'process raft request' (duration: 103.043251ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:44.427345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14605} {"level":"info","ts":"2026-10-26T22:00:44.519274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14605,"took":"88.618529ms","hash":827485402,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":31088640,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T22:00:44.519355Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":827485402,"revision":14605,"compact-revision":12598} {"level":"info","ts":"2026-10-26T22:04:55.463746Z","caller":"traceutil/trace.go:171","msg":"trace[38093286] linearizableReadLoop","detail":"{readStateIndex:19545; appliedIndex:19544; }","duration":"169.267147ms","start":"2026-10-26T22:04:55.294432Z","end":"2026-10-26T22:04:55.463699Z","steps":["trace[38093286] 'read index received' (duration: 168.919419ms)","trace[38093286] 'applied index is now lower than readState.Index' (duration: 345.658µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:04:55.463828Z","caller":"traceutil/trace.go:171","msg":"trace[677860777] transaction","detail":"{read_only:false; response_revision:18896; number_of_response:1; }","duration":"191.881584ms","start":"2026-10-26T22:04:55.271895Z","end":"2026-10-26T22:04:55.463776Z","steps":["trace[677860777] 'process raft request' (duration: 191.489925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:04:55.463997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.535944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:04:55.464085Z","caller":"traceutil/trace.go:171","msg":"trace[1809328205] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:18896; }","duration":"144.678237ms","start":"2026-10-26T22:04:55.319386Z","end":"2026-10-26T22:04:55.464064Z","steps":["trace[1809328205] 'agreement among raft nodes before linearized reading' (duration: 144.542204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:04:55.464115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.642975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29884200-tscbr\" ","response":"range_response_count:1 size:7038"} {"level":"info","ts":"2026-10-26T22:04:55.464163Z","caller":"traceutil/trace.go:171","msg":"trace[314362907] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29884200-tscbr; range_end:; response_count:1; response_revision:18896; }","duration":"169.727918ms","start":"2026-10-26T22:04:55.294416Z","end":"2026-10-26T22:04:55.464144Z","steps":["trace[314362907] 'agreement among raft nodes before linearized reading' (duration: 169.495112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:05:02.596406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.307904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-mlftl\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-10-26T22:05:02.596521Z","caller":"traceutil/trace.go:171","msg":"trace[1466185252] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-mlftl; range_end:; response_count:1; response_revision:18963; }","duration":"176.49263ms","start":"2026-10-26T22:05:02.420001Z","end":"2026-10-26T22:05:02.596494Z","steps":["trace[1466185252] 'range keys from in-memory index tree' (duration: 176.065279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:05:02.596503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.60211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-bzf5b\" ","response":"range_response_count:1 size:20901"} {"level":"info","ts":"2026-10-26T22:05:02.596778Z","caller":"traceutil/trace.go:171","msg":"trace[1485064904] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-bzf5b; range_end:; response_count:1; response_revision:18963; }","duration":"256.050361ms","start":"2026-10-26T22:05:02.3407Z","end":"2026-10-26T22:05:02.596751Z","steps":["trace[1485064904] 'range keys from in-memory index tree' (duration: 255.071298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:05:02.967468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.51233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:05:02.967572Z","caller":"traceutil/trace.go:171","msg":"trace[2052940578] linearizableReadLoop","detail":"{readStateIndex:19615; appliedIndex:19614; }","duration":"207.864611ms","start":"2026-10-26T22:05:02.75969Z","end":"2026-10-26T22:05:02.967554Z","steps":["trace[2052940578] 'read index received' (duration: 10.130016ms)","trace[2052940578] 'applied index is now lower than readState.Index' (duration: 197.733355ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:05:02.967885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.914221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:05:02.968348Z","caller":"traceutil/trace.go:171","msg":"trace[1044823853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18965; }","duration":"195.456254ms","start":"2026-10-26T22:05:02.772834Z","end":"2026-10-26T22:05:02.96829Z","steps":["trace[1044823853] 'agreement among raft nodes before linearized reading' (duration: 194.909772ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:05:02.96811Z","caller":"traceutil/trace.go:171","msg":"trace[893934641] transaction","detail":"{read_only:false; response_revision:18965; number_of_response:1; }","duration":"332.374207ms","start":"2026-10-26T22:05:02.635695Z","end":"2026-10-26T22:05:02.968069Z","steps":["trace[893934641] 'process raft request' (duration: 134.146981ms)","trace[893934641] 'compare' (duration: 197.340657ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:05:02.968197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.65421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-2pp5x\" ","response":"range_response_count:1 size:9792"} {"level":"warn","ts":"2026-10-26T22:05:02.967984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.26472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"warn","ts":"2026-10-26T22:05:02.968765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:05:02.635666Z","time spent":"332.889108ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:05:02.968898Z","caller":"traceutil/trace.go:171","msg":"trace[203515503] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-2pp5x; range_end:; response_count:1; response_revision:18965; }","duration":"180.343545ms","start":"2026-10-26T22:05:02.788477Z","end":"2026-10-26T22:05:02.968821Z","steps":["trace[203515503] 'agreement among raft nodes before linearized reading' (duration: 179.618589ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:05:02.968957Z","caller":"traceutil/trace.go:171","msg":"trace[132710872] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:18965; }","duration":"209.287813ms","start":"2026-10-26T22:05:02.759631Z","end":"2026-10-26T22:05:02.968919Z","steps":["trace[132710872] 'agreement among raft nodes before linearized reading' (duration: 208.061676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:05:03.429595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.820176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:05:03.429708Z","caller":"traceutil/trace.go:171","msg":"trace[1559765803] transaction","detail":"{read_only:false; response_revision:18968; number_of_response:1; }","duration":"407.206192ms","start":"2026-10-26T22:05:03.022479Z","end":"2026-10-26T22:05:03.429685Z","steps":["trace[1559765803] 'process raft request' (duration: 218.183531ms)","trace[1559765803] 'compare' (duration: 188.359336ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:05:03.429771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:05:03.022457Z","time spent":"407.282544ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:05:03.493483Z","caller":"traceutil/trace.go:171","msg":"trace[645897099] transaction","detail":"{read_only:false; response_revision:18969; number_of_response:1; }","duration":"418.706491ms","start":"2026-10-26T22:05:03.074733Z","end":"2026-10-26T22:05:03.49344Z","steps":["trace[645897099] 'process raft request' (duration: 418.170089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:05:03.49369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:05:03.074696Z","time spent":"418.874815ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:05:03.526261Z","caller":"traceutil/trace.go:171","msg":"trace[78444343] linearizableReadLoop","detail":"{readStateIndex:19620; appliedIndex:19617; }","duration":"320.027382ms","start":"2026-10-26T22:05:03.206192Z","end":"2026-10-26T22:05:03.526219Z","steps":["trace[78444343] 'read index received' (duration: 34.485952ms)","trace[78444343] 'applied index is now lower than readState.Index' (duration: 285.53937ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:05:03.526727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.953963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T22:05:03.526762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.567274ms","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-10-26T22:05:03.526924Z","caller":"traceutil/trace.go:171","msg":"trace[837362437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18969; }","duration":"320.681006ms","start":"2026-10-26T22:05:03.206116Z","end":"2026-10-26T22:05:03.526797Z","steps":["trace[837362437] 'agreement among raft nodes before linearized reading' (duration: 320.313437ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:05:03.526879Z","caller":"traceutil/trace.go:171","msg":"trace[1761552762] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:18969; }","duration":"296.066356ms","start":"2026-10-26T22:05:03.230717Z","end":"2026-10-26T22:05:03.526783Z","steps":["trace[1761552762] 'agreement among raft nodes before linearized reading' (duration: 295.913623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:05:03.52699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.746972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T22:05:03.526988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:05:03.20609Z","time spent":"320.884391ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T22:05:03.527101Z","caller":"traceutil/trace.go:171","msg":"trace[1381183666] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:18969; }","duration":"221.945768ms","start":"2026-10-26T22:05:03.305129Z","end":"2026-10-26T22:05:03.527075Z","steps":["trace[1381183666] 'agreement among raft nodes before linearized reading' (duration: 221.710312ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:05:37.714813Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:05:37.722561Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T22:05:37.722741Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T22:05:44.435774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16945} {"level":"info","ts":"2026-10-26T22:05:44.547345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16945,"took":"106.908523ms","hash":1797675402,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":33263616,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T22:05:44.547434Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1797675402,"revision":16945,"compact-revision":14605} {"level":"info","ts":"2026-10-26T22:10:44.443711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19526} {"level":"info","ts":"2026-10-26T22:10:44.576381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19526,"took":"127.106591ms","hash":801137493,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":30507008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T22:10:44.576454Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":801137493,"revision":19526,"compact-revision":16945} {"level":"info","ts":"2026-10-26T22:10:47.07322Z","caller":"traceutil/trace.go:171","msg":"trace[2081582165] transaction","detail":"{read_only:false; response_revision:21673; number_of_response:1; }","duration":"109.959954ms","start":"2026-10-26T22:10:46.963232Z","end":"2026-10-26T22:10:47.073192Z","steps":["trace[2081582165] 'process raft request' (duration: 109.78024ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:10:47.107783Z","caller":"traceutil/trace.go:171","msg":"trace[1648079634] transaction","detail":"{read_only:false; response_revision:21674; number_of_response:1; }","duration":"138.317524ms","start":"2026-10-26T22:10:46.969434Z","end":"2026-10-26T22:10:47.107751Z","steps":["trace[1648079634] 'process raft request' (duration: 138.073229ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:11:08.260404Z","caller":"traceutil/trace.go:171","msg":"trace[1456225329] transaction","detail":"{read_only:false; response_revision:21783; number_of_response:1; }","duration":"166.619644ms","start":"2026-10-26T22:11:08.093728Z","end":"2026-10-26T22:11:08.260348Z","steps":["trace[1456225329] 'process raft request' (duration: 148.675178ms)","trace[1456225329] 'compare' (duration: 17.67498ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:11:08.576686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.011486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:11:08.576843Z","caller":"traceutil/trace.go:171","msg":"trace[1990962527] linearizableReadLoop","detail":"{readStateIndex:22530; appliedIndex:22529; }","duration":"227.925639ms","start":"2026-10-26T22:11:08.348894Z","end":"2026-10-26T22:11:08.576819Z","steps":["trace[1990962527] 'read index received' (duration: 86.662358ms)","trace[1990962527] 'applied index is now lower than readState.Index' (duration: 141.261471ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:11:08.576971Z","caller":"traceutil/trace.go:171","msg":"trace[584297322] transaction","detail":"{read_only:false; response_revision:21785; number_of_response:1; }","duration":"277.009486ms","start":"2026-10-26T22:11:08.299917Z","end":"2026-10-26T22:11:08.576926Z","steps":["trace[584297322] 'process raft request' (duration: 135.589752ms)","trace[584297322] 'compare' (duration: 140.630156ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:11:08.577096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.873012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T22:11:08.57711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.205494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-26T22:11:08.577192Z","caller":"traceutil/trace.go:171","msg":"trace[670311596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21785; }","duration":"195.013364ms","start":"2026-10-26T22:11:08.382154Z","end":"2026-10-26T22:11:08.577168Z","steps":["trace[670311596] 'agreement among raft nodes before linearized reading' (duration: 194.846121ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:11:08.577202Z","caller":"traceutil/trace.go:171","msg":"trace[1484149077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21785; }","duration":"228.380159ms","start":"2026-10-26T22:11:08.348798Z","end":"2026-10-26T22:11:08.577179Z","steps":["trace[1484149077] 'agreement among raft nodes before linearized reading' (duration: 228.133513ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:11:08.890456Z","caller":"traceutil/trace.go:171","msg":"trace[1868063338] linearizableReadLoop","detail":"{readStateIndex:22531; appliedIndex:22530; }","duration":"118.2635ms","start":"2026-10-26T22:11:08.772157Z","end":"2026-10-26T22:11:08.890421Z","steps":["trace[1868063338] 'read index received' (duration: 90.396601ms)","trace[1868063338] 'applied index is now lower than readState.Index' (duration: 27.865149ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:11:08.890591Z","caller":"traceutil/trace.go:171","msg":"trace[1360453613] transaction","detail":"{read_only:false; response_revision:21786; number_of_response:1; }","duration":"308.385225ms","start":"2026-10-26T22:11:08.582191Z","end":"2026-10-26T22:11:08.890576Z","steps":["trace[1360453613] 'process raft request' (duration: 280.483345ms)","trace[1360453613] 'compare' (duration: 27.603903ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:11:08.890712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:11:08.582159Z","time spent":"308.458237ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:11:08.890777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.5998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:11:08.890878Z","caller":"traceutil/trace.go:171","msg":"trace[957394949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21786; }","duration":"118.751153ms","start":"2026-10-26T22:11:08.77209Z","end":"2026-10-26T22:11:08.890841Z","steps":["trace[957394949] 'agreement among raft nodes before linearized reading' (duration: 118.61075ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:11:09.323529Z","caller":"traceutil/trace.go:171","msg":"trace[1395078440] transaction","detail":"{read_only:false; response_revision:21789; number_of_response:1; }","duration":"118.549767ms","start":"2026-10-26T22:11:09.20495Z","end":"2026-10-26T22:11:09.3235Z","steps":["trace[1395078440] 'process raft request' (duration: 118.362073ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:11:09.336337Z","caller":"traceutil/trace.go:171","msg":"trace[1555554620] transaction","detail":"{read_only:false; response_revision:21790; number_of_response:1; }","duration":"108.042501ms","start":"2026-10-26T22:11:09.228277Z","end":"2026-10-26T22:11:09.33632Z","steps":["trace[1555554620] 'process raft request' (duration: 107.841807ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:11:09.529981Z","caller":"traceutil/trace.go:171","msg":"trace[18419315] transaction","detail":"{read_only:false; response_revision:21791; number_of_response:1; }","duration":"187.869193ms","start":"2026-10-26T22:11:09.342058Z","end":"2026-10-26T22:11:09.529927Z","steps":["trace[18419315] 'process raft request' (duration: 146.176581ms)","trace[18419315] 'compare' (duration: 41.397335ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:15:44.45205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21661} {"level":"info","ts":"2026-10-26T22:15:44.573403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21661,"took":"116.690875ms","hash":1093052604,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":42385408,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-26T22:15:44.57348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1093052604,"revision":21661,"compact-revision":19526} {"level":"info","ts":"2026-10-26T22:18:10.765606Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006910.wal"} {"level":"warn","ts":"2026-10-26T22:18:43.459057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.003591ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-26T22:18:43.460281Z","caller":"traceutil/trace.go:171","msg":"trace[797887091] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"206.579756ms","start":"2026-10-26T22:18:43.253649Z","end":"2026-10-26T22:18:43.460229Z","steps":["trace[797887091] 'process raft request' (duration: 205.536632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:43.604311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.110308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:18:43.604804Z","caller":"traceutil/trace.go:171","msg":"trace[1602591947] linearizableReadLoop","detail":"{readStateIndex:27218; appliedIndex:27215; }","duration":"325.216493ms","start":"2026-10-26T22:18:43.279563Z","end":"2026-10-26T22:18:43.60478Z","steps":["trace[1602591947] 'read index received' (duration: 24.402189ms)","trace[1602591947] 'applied index is now lower than readState.Index' (duration: 300.812304ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:18:43.604994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.420158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T22:18:43.604963Z","caller":"traceutil/trace.go:171","msg":"trace[1031290391] transaction","detail":"{read_only:false; response_revision:26344; number_of_response:1; }","duration":"338.82188ms","start":"2026-10-26T22:18:43.266071Z","end":"2026-10-26T22:18:43.604893Z","steps":["trace[1031290391] 'process raft request' (duration: 194.983825ms)","trace[1031290391] 'compare' (duration: 142.559886ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:18:43.605043Z","caller":"traceutil/trace.go:171","msg":"trace[1202617785] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:26344; }","duration":"325.50741ms","start":"2026-10-26T22:18:43.279519Z","end":"2026-10-26T22:18:43.605026Z","steps":["trace[1202617785] 'agreement among raft nodes before linearized reading' (duration: 325.394297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:43.605094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:18:43.279506Z","time spent":"325.576572ms","remote":"127.0.0.1:49598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":9,"response size":31,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-26T22:18:43.605197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:18:43.266057Z","time spent":"339.009244ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:18:43.605184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.642288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-26T22:18:43.605312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.301115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-26T22:18:43.605335Z","caller":"traceutil/trace.go:171","msg":"trace[1110613584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26344; }","duration":"307.807253ms","start":"2026-10-26T22:18:43.297483Z","end":"2026-10-26T22:18:43.60529Z","steps":["trace[1110613584] 'agreement among raft nodes before linearized reading' (duration: 307.515356ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:43.605399Z","caller":"traceutil/trace.go:171","msg":"trace[2142146357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26344; }","duration":"136.409457ms","start":"2026-10-26T22:18:43.468961Z","end":"2026-10-26T22:18:43.605371Z","steps":["trace[2142146357] 'agreement among raft nodes before linearized reading' (duration: 136.273214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:43.605484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:18:43.297473Z","time spent":"307.959586ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T22:18:43.60532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.139107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:18:43.605594Z","caller":"traceutil/trace.go:171","msg":"trace[1627100922] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:26344; }","duration":"259.433244ms","start":"2026-10-26T22:18:43.346144Z","end":"2026-10-26T22:18:43.605577Z","steps":["trace[1627100922] 'agreement among raft nodes before linearized reading' (duration: 259.108907ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:43.873215Z","caller":"traceutil/trace.go:171","msg":"trace[1109690479] linearizableReadLoop","detail":"{readStateIndex:27222; appliedIndex:27219; }","duration":"100.363427ms","start":"2026-10-26T22:18:43.772817Z","end":"2026-10-26T22:18:43.873181Z","steps":["trace[1109690479] 'read index received' (duration: 99.977169ms)","trace[1109690479] 'applied index is now lower than readState.Index' (duration: 384.648µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:18:43.873258Z","caller":"traceutil/trace.go:171","msg":"trace[1472719276] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"235.027775ms","start":"2026-10-26T22:18:43.638187Z","end":"2026-10-26T22:18:43.873214Z","steps":["trace[1472719276] 'process raft request' (duration: 234.876272ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:43.873274Z","caller":"traceutil/trace.go:171","msg":"trace[1312905972] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"230.393321ms","start":"2026-10-26T22:18:43.642843Z","end":"2026-10-26T22:18:43.873236Z","steps":["trace[1312905972] 'process raft request' (duration: 230.279138ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:43.873481Z","caller":"traceutil/trace.go:171","msg":"trace[727594052] transaction","detail":"{read_only:false; response_revision:26346; number_of_response:1; }","duration":"262.563275ms","start":"2026-10-26T22:18:43.610878Z","end":"2026-10-26T22:18:43.873442Z","steps":["trace[727594052] 'process raft request' (duration: 261.841609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:18:43.873912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.045002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:18:43.873994Z","caller":"traceutil/trace.go:171","msg":"trace[355511518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26348; }","duration":"101.232726ms","start":"2026-10-26T22:18:43.772741Z","end":"2026-10-26T22:18:43.873974Z","steps":["trace[355511518] 'agreement among raft nodes before linearized reading' (duration: 101.046022ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:44.046858Z","caller":"traceutil/trace.go:171","msg":"trace[1410270120] transaction","detail":"{read_only:false; response_revision:26350; number_of_response:1; }","duration":"142.631598ms","start":"2026-10-26T22:18:43.904208Z","end":"2026-10-26T22:18:44.046839Z","steps":["trace[1410270120] 'process raft request' (duration: 74.386123ms)","trace[1410270120] 'compare' (duration: 68.05244ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:18:44.047129Z","caller":"traceutil/trace.go:171","msg":"trace[1759778502] transaction","detail":"{read_only:false; response_revision:26351; number_of_response:1; }","duration":"132.116ms","start":"2026-10-26T22:18:43.914984Z","end":"2026-10-26T22:18:44.0471Z","steps":["trace[1759778502] 'process raft request' (duration: 131.808964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:19:46.214074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.273923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:19:46.214282Z","caller":"traceutil/trace.go:171","msg":"trace[625194009] transaction","detail":"{read_only:false; response_revision:26906; number_of_response:1; }","duration":"184.133751ms","start":"2026-10-26T22:19:46.030098Z","end":"2026-10-26T22:19:46.214231Z","steps":["trace[625194009] 'process raft request' (duration: 48.601083ms)","trace[625194009] 'compare' (duration: 134.816982ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:19:46.51641Z","caller":"traceutil/trace.go:171","msg":"trace[1169717782] linearizableReadLoop","detail":"{readStateIndex:27794; appliedIndex:27793; }","duration":"302.204396ms","start":"2026-10-26T22:19:46.214175Z","end":"2026-10-26T22:19:46.51638Z","steps":["trace[1169717782] 'read index received' (duration: 301.97716ms)","trace[1169717782] 'applied index is now lower than readState.Index' (duration: 225.716µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:19:46.516554Z","caller":"traceutil/trace.go:171","msg":"trace[379541851] transaction","detail":"{read_only:false; response_revision:26907; number_of_response:1; }","duration":"364.799215ms","start":"2026-10-26T22:19:46.151722Z","end":"2026-10-26T22:19:46.516521Z","steps":["trace[379541851] 'process raft request' (duration: 364.389664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:19:46.516729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:19:46.151697Z","time spent":"364.935238ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:19:46.516757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.322409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129117"} {"level":"info","ts":"2026-10-26T22:19:46.516796Z","caller":"traceutil/trace.go:171","msg":"trace[1258692231] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:26907; }","duration":"354.40106ms","start":"2026-10-26T22:19:46.162383Z","end":"2026-10-26T22:19:46.516784Z","steps":["trace[1258692231] 'agreement among raft nodes before linearized reading' (duration: 354.135775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:19:46.516828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:19:46.162358Z","time spent":"354.462832ms","remote":"127.0.0.1:48924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129140,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-10-26T22:19:46.709299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.76666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:19:46.709505Z","caller":"traceutil/trace.go:171","msg":"trace[1396557434] linearizableReadLoop","detail":"{readStateIndex:27796; appliedIndex:27794; }","duration":"193.00086ms","start":"2026-10-26T22:19:46.516488Z","end":"2026-10-26T22:19:46.709488Z","steps":["trace[1396557434] 'read index received' (duration: 37.582705ms)","trace[1396557434] 'applied index is now lower than readState.Index' (duration: 155.417275ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:19:46.709589Z","caller":"traceutil/trace.go:171","msg":"trace[1764397499] transaction","detail":"{read_only:false; response_revision:26909; number_of_response:1; }","duration":"379.402293ms","start":"2026-10-26T22:19:46.330176Z","end":"2026-10-26T22:19:46.709578Z","steps":["trace[1764397499] 'process raft request' (duration: 379.208448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:19:46.709649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:19:46.330145Z","time spent":"379.467124ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:19:46.709896Z","caller":"traceutil/trace.go:171","msg":"trace[417201385] transaction","detail":"{read_only:false; response_revision:26908; number_of_response:1; }","duration":"382.539883ms","start":"2026-10-26T22:19:46.327312Z","end":"2026-10-26T22:19:46.709852Z","steps":["trace[417201385] 'process raft request' (duration: 226.836191ms)","trace[417201385] 'compare' (duration: 154.361262ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:19:46.710039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:19:46.327279Z","time spent":"382.670556ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:19:46.710436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.860893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:91 size:120749"} {"level":"info","ts":"2026-10-26T22:19:46.710477Z","caller":"traceutil/trace.go:171","msg":"trace[1843981004] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:91; response_revision:26910; }","duration":"186.967275ms","start":"2026-10-26T22:19:46.523499Z","end":"2026-10-26T22:19:46.710467Z","steps":["trace[1843981004] 'agreement among raft nodes before linearized reading' (duration: 186.261219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:19:46.711045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.932878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:91 size:120749"} {"level":"info","ts":"2026-10-26T22:19:46.711157Z","caller":"traceutil/trace.go:171","msg":"trace[1353774307] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:91; response_revision:26910; }","duration":"494.108392ms","start":"2026-10-26T22:19:46.217024Z","end":"2026-10-26T22:19:46.711133Z","steps":["trace[1353774307] 'agreement among raft nodes before linearized reading' (duration: 492.642259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:19:46.711213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:19:46.217002Z","time spent":"494.198433ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":91,"response size":120772,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-10-26T22:19:46.711437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.217444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-10-26T22:19:46.71148Z","caller":"traceutil/trace.go:171","msg":"trace[2066743325] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26910; }","duration":"430.314867ms","start":"2026-10-26T22:19:46.281155Z","end":"2026-10-26T22:19:46.71147Z","steps":["trace[2066743325] 'agreement among raft nodes before linearized reading' (duration: 428.551458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:19:46.711507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:19:46.281121Z","time spent":"430.379758ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-26T22:19:46.715389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.94913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-26T22:19:46.715643Z","caller":"traceutil/trace.go:171","msg":"trace[1067608056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26910; }","duration":"494.260356ms","start":"2026-10-26T22:19:46.221371Z","end":"2026-10-26T22:19:46.715631Z","steps":["trace[1067608056] 'agreement among raft nodes before linearized reading' (duration: 488.345353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:19:46.715672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:19:46.221347Z","time spent":"494.317607ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T22:19:46.977948Z","caller":"traceutil/trace.go:171","msg":"trace[981651096] transaction","detail":"{read_only:false; response_revision:26912; number_of_response:1; }","duration":"257.653595ms","start":"2026-10-26T22:19:46.720149Z","end":"2026-10-26T22:19:46.977803Z","steps":["trace[981651096] 'process raft request' (duration: 200.94922ms)","trace[981651096] 'compare' (duration: 56.539251ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:19:46.9971Z","caller":"traceutil/trace.go:171","msg":"trace[1835301947] transaction","detail":"{read_only:false; response_revision:26913; number_of_response:1; }","duration":"273.601924ms","start":"2026-10-26T22:19:46.723456Z","end":"2026-10-26T22:19:46.997058Z","steps":["trace[1835301947] 'process raft request' (duration: 273.289457ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:19:46.997278Z","caller":"traceutil/trace.go:171","msg":"trace[2006697230] linearizableReadLoop","detail":"{readStateIndex:27800; appliedIndex:27798; }","duration":"273.351307ms","start":"2026-10-26T22:19:46.723894Z","end":"2026-10-26T22:19:46.997245Z","steps":["trace[2006697230] 'read index received' (duration: 197.223555ms)","trace[2006697230] 'applied index is now lower than readState.Index' (duration: 76.125362ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:19:46.997424Z","caller":"traceutil/trace.go:171","msg":"trace[348635692] transaction","detail":"{read_only:false; response_revision:26914; number_of_response:1; }","duration":"139.335323ms","start":"2026-10-26T22:19:46.858052Z","end":"2026-10-26T22:19:46.997387Z","steps":["trace[348635692] 'process raft request' (duration: 138.924654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:19:46.997615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.688964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"warn","ts":"2026-10-26T22:19:46.997606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.25359ms","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:6"} {"level":"info","ts":"2026-10-26T22:19:46.997654Z","caller":"traceutil/trace.go:171","msg":"trace[384962829] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:26914; }","duration":"273.789267ms","start":"2026-10-26T22:19:46.723854Z","end":"2026-10-26T22:19:46.997643Z","steps":["trace[384962829] 'agreement among raft nodes before linearized reading' (duration: 273.577592ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:19:46.997709Z","caller":"traceutil/trace.go:171","msg":"trace[1173324278] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26914; }","duration":"267.410014ms","start":"2026-10-26T22:19:46.730273Z","end":"2026-10-26T22:19:46.997683Z","steps":["trace[1173324278] 'agreement among raft nodes before linearized reading' (duration: 267.213239ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:20:44.459526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24589} {"level":"info","ts":"2026-10-26T22:20:44.620451Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24589,"took":"154.809682ms","hash":2685972270,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":36491264,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T22:20:44.620523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2685972270,"revision":24589,"compact-revision":21661} {"level":"info","ts":"2026-10-26T22:23:25.906128Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:23:25.913044Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T22:23:25.913264Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T22:23:27.886105Z","caller":"traceutil/trace.go:171","msg":"trace[1680387431] transaction","detail":"{read_only:false; response_revision:29074; number_of_response:1; }","duration":"136.145995ms","start":"2026-10-26T22:23:27.749893Z","end":"2026-10-26T22:23:27.886039Z","steps":["trace[1680387431] 'process raft request' (duration: 135.956601ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:23:27.900445Z","caller":"traceutil/trace.go:171","msg":"trace[630115140] transaction","detail":"{read_only:false; response_revision:29075; number_of_response:1; }","duration":"115.913646ms","start":"2026-10-26T22:23:27.784483Z","end":"2026-10-26T22:23:27.900397Z","steps":["trace[630115140] 'process raft request' (duration: 115.455935ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:23:27.900936Z","caller":"traceutil/trace.go:171","msg":"trace[261887190] transaction","detail":"{read_only:false; response_revision:29076; number_of_response:1; }","duration":"113.338358ms","start":"2026-10-26T22:23:27.78756Z","end":"2026-10-26T22:23:27.900899Z","steps":["trace[261887190] 'process raft request' (duration: 112.5869ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:24:02.440695Z","caller":"traceutil/trace.go:171","msg":"trace[1795541556] transaction","detail":"{read_only:false; response_revision:29442; number_of_response:1; }","duration":"102.206989ms","start":"2026-10-26T22:24:02.338461Z","end":"2026-10-26T22:24:02.440668Z","steps":["trace[1795541556] 'process raft request' (duration: 102.085936ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:25:44.467443Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27542} {"level":"info","ts":"2026-10-26T22:25:44.624549Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27542,"took":"151.912109ms","hash":3329737393,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":34787328,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T22:25:44.624726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3329737393,"revision":27542,"compact-revision":24589} {"level":"info","ts":"2026-10-26T22:30:44.474435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30243} {"level":"info","ts":"2026-10-26T22:30:44.634983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30243,"took":"154.646297ms","hash":1316878279,"current-db-size-bytes":55107584,"current-db-size":"55 MB","current-db-size-in-use-bytes":30998528,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T22:30:44.635065Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1316878279,"revision":30243,"compact-revision":27542} {"level":"info","ts":"2026-10-26T22:35:44.481887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32497} {"level":"info","ts":"2026-10-26T22:35:44.649078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32497,"took":"161.278939ms","hash":3883970974,"current-db-size-bytes":56147968,"current-db-size":"56 MB","current-db-size-in-use-bytes":31072256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T22:35:44.649141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3883970974,"revision":32497,"compact-revision":30243} {"level":"info","ts":"2026-10-26T22:40:44.490173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34721} {"level":"info","ts":"2026-10-26T22:40:44.646774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34721,"took":"149.68285ms","hash":1751584132,"current-db-size-bytes":56147968,"current-db-size":"56 MB","current-db-size-in-use-bytes":30715904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T22:40:44.64687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1751584132,"revision":34721,"compact-revision":32497} {"level":"info","ts":"2026-10-26T22:44:39.63061Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:44:39.637969Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T22:44:39.63809Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T22:45:44.497683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37008} {"level":"info","ts":"2026-10-26T22:45:44.640245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37008,"took":"137.021884ms","hash":3191476140,"current-db-size-bytes":56147968,"current-db-size":"56 MB","current-db-size-in-use-bytes":32550912,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T22:45:44.640338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3191476140,"revision":37008,"compact-revision":34721} {"level":"info","ts":"2026-10-26T22:50:44.505872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39297} {"level":"info","ts":"2026-10-26T22:50:44.650552Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39297,"took":"139.043779ms","hash":288978880,"current-db-size-bytes":57430016,"current-db-size":"57 MB","current-db-size-in-use-bytes":32112640,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T22:50:44.650655Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":288978880,"revision":39297,"compact-revision":37008} {"level":"info","ts":"2026-10-26T22:55:44.513975Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41535} {"level":"info","ts":"2026-10-26T22:55:44.656247Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41535,"took":"136.886456ms","hash":3752394046,"current-db-size-bytes":57430016,"current-db-size":"57 MB","current-db-size-in-use-bytes":29458432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T22:55:44.656351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3752394046,"revision":41535,"compact-revision":39297}