{"level":"warn","ts":"2026-10-20T14:12:42.977901Z","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-20T14:12:42.977994Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.167: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.167:2380","--initial-cluster=instance=https://162.253.55.167:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.167:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.167: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-20T14:12:42.97808Z","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-20T14:12:42.978097Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-10-20T14:12:42.978119Z","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-20T14:12:42.979183Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"]} {"level":"info","ts":"2026-10-20T14:12:42.979304Z","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.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.167: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-20T14:12:42.984063Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.496683ms"} {"level":"info","ts":"2026-10-20T14:12:42.989755Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"83287f3a81241ad4","cluster-id":"185ed9b8ed398b9a"} {"level":"info","ts":"2026-10-20T14:12:42.98984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=()"} {"level":"info","ts":"2026-10-20T14:12:42.989861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 0"} {"level":"info","ts":"2026-10-20T14:12:42.989873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 83287f3a81241ad4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-20T14:12:42.9899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 1"} {"level":"info","ts":"2026-10-20T14:12:42.989942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"warn","ts":"2026-10-20T14:12:42.996423Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-20T14:12:42.997813Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-20T14:12:42.998991Z","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-20T14:12:43.001303Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"83287f3a81241ad4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-20T14:12:43.001453Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"83287f3a81241ad4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-20T14:12:43.001642Z","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-20T14:12:43.001796Z","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-20T14:12:43.001813Z","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-20T14:12:43.003475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"info","ts":"2026-10-20T14:12:43.003689Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","added-peer-id":"83287f3a81241ad4","added-peer-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-10-20T14:12:43.007083Z","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-20T14:12:43.007238Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-10-20T14:12:43.007336Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-10-20T14:12:43.00743Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"83287f3a81241ad4","initial-advertise-peer-urls":["https://162.253.55.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-20T14:12:43.007498Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-20T14:12:43.491146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 is starting a new election at term 1"} {"level":"info","ts":"2026-10-20T14:12:43.491219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-20T14:12:43.491242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgPreVoteResp from 83287f3a81241ad4 at term 1"} {"level":"info","ts":"2026-10-20T14:12:43.491265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became candidate at term 2"} {"level":"info","ts":"2026-10-20T14:12:43.491286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgVoteResp from 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-10-20T14:12:43.491306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became leader at term 2"} {"level":"info","ts":"2026-10-20T14:12:43.49133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 83287f3a81241ad4 elected leader 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-10-20T14:12:43.499019Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-20T14:12:43.501365Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"83287f3a81241ad4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.167:2379]}","request-path":"/0/members/83287f3a81241ad4/attributes","cluster-id":"185ed9b8ed398b9a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-20T14:12:43.501587Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-20T14:12:43.501621Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-20T14:12:43.502245Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-20T14:12:43.502354Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-20T14:12:43.504885Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-20T14:12:43.507049Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.167:2379"} {"level":"info","ts":"2026-10-20T14:12:43.507953Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","cluster-version":"3.5"} {"level":"info","ts":"2026-10-20T14:12:43.508073Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-20T14:12:43.508275Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-20T14:13:15.710505Z","caller":"traceutil/trace.go:171","msg":"trace[1703160440] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"159.425431ms","start":"2026-10-20T14:13:15.551044Z","end":"2026-10-20T14:13:15.71047Z","steps":["trace[1703160440] 'process raft request' (duration: 81.562435ms)","trace[1703160440] 'compare' (duration: 77.607751ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:13:15.710479Z","caller":"traceutil/trace.go:171","msg":"trace[1434449958] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"108.653863ms","start":"2026-10-20T14:13:15.601788Z","end":"2026-10-20T14:13:15.710442Z","steps":["trace[1434449958] 'read index received' (duration: 30.816787ms)","trace[1434449958] 'applied index is now lower than readState.Index' (duration: 77.835506ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T14:13:15.710732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.902507ms","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-20T14:13:15.710854Z","caller":"traceutil/trace.go:171","msg":"trace[1274041468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:478; }","duration":"109.07557ms","start":"2026-10-20T14:13:15.601759Z","end":"2026-10-20T14:13:15.710835Z","steps":["trace[1274041468] 'agreement among raft nodes before linearized reading' (duration: 108.862776ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:13:31.74687Z","caller":"traceutil/trace.go:171","msg":"trace[797468094] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"123.597439ms","start":"2026-10-20T14:13:31.623244Z","end":"2026-10-20T14:13:31.746842Z","steps":["trace[797468094] 'read index received' (duration: 123.528928ms)","trace[797468094] 'applied index is now lower than readState.Index' (duration: 67.171µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:13:31.746965Z","caller":"traceutil/trace.go:171","msg":"trace[105143475] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"143.201265ms","start":"2026-10-20T14:13:31.603728Z","end":"2026-10-20T14:13:31.746929Z","steps":["trace[105143475] 'process raft request' (duration: 142.95629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:13:31.747241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.964306ms","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-20T14:13:31.747349Z","caller":"traceutil/trace.go:171","msg":"trace[456339517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"124.138409ms","start":"2026-10-20T14:13:31.623183Z","end":"2026-10-20T14:13:31.747322Z","steps":["trace[456339517] 'agreement among raft nodes before linearized reading' (duration: 123.791913ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:13:32.036344Z","caller":"traceutil/trace.go:171","msg":"trace[1371071470] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"129.583461ms","start":"2026-10-20T14:13:31.906735Z","end":"2026-10-20T14:13:32.036319Z","steps":["trace[1371071470] 'process raft request' (duration: 129.366457ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:13:32.662289Z","caller":"traceutil/trace.go:171","msg":"trace[1801218417] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"443.546667ms","start":"2026-10-20T14:13:32.218704Z","end":"2026-10-20T14:13:32.662251Z","steps":["trace[1801218417] 'process raft request' (duration: 442.441155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:13:32.66327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T14:13:32.218672Z","time spent":"443.890803ms","remote":"127.0.0.1:34874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T14:14:16.230179Z","caller":"traceutil/trace.go:171","msg":"trace[1409278458] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"191.334995ms","start":"2026-10-20T14:14:16.038819Z","end":"2026-10-20T14:14:16.230154Z","steps":["trace[1409278458] 'read index received' (duration: 191.174422ms)","trace[1409278458] 'applied index is now lower than readState.Index' (duration: 159.643µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:14:16.230245Z","caller":"traceutil/trace.go:171","msg":"trace[697478277] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"233.038284ms","start":"2026-10-20T14:14:15.997173Z","end":"2026-10-20T14:14:16.230211Z","steps":["trace[697478277] 'process raft request' (duration: 232.82212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:14:16.23045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.60208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-20T14:14:16.230526Z","caller":"traceutil/trace.go:171","msg":"trace[1329482884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1205; }","duration":"191.697481ms","start":"2026-10-20T14:14:16.038813Z","end":"2026-10-20T14:14:16.23051Z","steps":["trace[1329482884] 'agreement among raft nodes before linearized reading' (duration: 191.491638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:14:16.230627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.629792ms","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-20T14:14:16.230682Z","caller":"traceutil/trace.go:171","msg":"trace[1848555887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1205; }","duration":"182.722013ms","start":"2026-10-20T14:14:16.047946Z","end":"2026-10-20T14:14:16.230668Z","steps":["trace[1848555887] 'agreement among raft nodes before linearized reading' (duration: 182.503849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:14:16.230796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.314507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-20T14:14:16.230839Z","caller":"traceutil/trace.go:171","msg":"trace[268700814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1205; }","duration":"136.396759ms","start":"2026-10-20T14:14:16.094427Z","end":"2026-10-20T14:14:16.230824Z","steps":["trace[268700814] 'agreement among raft nodes before linearized reading' (duration: 136.293286ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:15:04.745003Z","caller":"traceutil/trace.go:171","msg":"trace[1648328203] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"178.358614ms","start":"2026-10-20T14:15:04.566585Z","end":"2026-10-20T14:15:04.744944Z","steps":["trace[1648328203] 'process raft request' (duration: 177.508107ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:15:04.745363Z","caller":"traceutil/trace.go:171","msg":"trace[2094929637] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"176.57654ms","start":"2026-10-20T14:15:04.568752Z","end":"2026-10-20T14:15:04.745328Z","steps":["trace[2094929637] 'process raft request' (duration: 176.432528ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:15:52.929083Z","caller":"traceutil/trace.go:171","msg":"trace[2031299182] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"119.417355ms","start":"2026-10-20T14:15:52.809631Z","end":"2026-10-20T14:15:52.929048Z","steps":["trace[2031299182] 'process raft request' (duration: 27.271843ms)","trace[2031299182] 'compare' (duration: 91.979219ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:15:52.929631Z","caller":"traceutil/trace.go:171","msg":"trace[1669809490] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"119.595768ms","start":"2026-10-20T14:15:52.810003Z","end":"2026-10-20T14:15:52.929599Z","steps":["trace[1669809490] 'process raft request' (duration: 119.470316ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:15:52.929632Z","caller":"traceutil/trace.go:171","msg":"trace[1623236156] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"119.847563ms","start":"2026-10-20T14:15:52.809722Z","end":"2026-10-20T14:15:52.92957Z","steps":["trace[1623236156] 'process raft request' (duration: 119.288993ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:15:53.127291Z","caller":"traceutil/trace.go:171","msg":"trace[668478780] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"212.589477ms","start":"2026-10-20T14:15:52.91467Z","end":"2026-10-20T14:15:53.127259Z","steps":["trace[668478780] 'process raft request' (duration: 183.852636ms)","trace[668478780] 'compare' (duration: 28.628169ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:15:58.949781Z","caller":"traceutil/trace.go:171","msg":"trace[501211051] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"115.699854ms","start":"2026-10-20T14:15:58.83402Z","end":"2026-10-20T14:15:58.949719Z","steps":["trace[501211051] 'process raft request' (duration: 115.539041ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:15:59.066906Z","caller":"traceutil/trace.go:171","msg":"trace[629622327] linearizableReadLoop","detail":"{readStateIndex:2319; appliedIndex:2318; }","duration":"108.620232ms","start":"2026-10-20T14:15:58.958262Z","end":"2026-10-20T14:15:59.066882Z","steps":["trace[629622327] 'read index received' (duration: 107.98598ms)","trace[629622327] 'applied index is now lower than readState.Index' (duration: 633.222µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:15:59.066961Z","caller":"traceutil/trace.go:171","msg":"trace[769940464] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"108.696514ms","start":"2026-10-20T14:15:58.958231Z","end":"2026-10-20T14:15:59.066927Z","steps":["trace[769940464] 'process raft request' (duration: 108.006691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:15:59.067119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.820156ms","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-20T14:15:59.067201Z","caller":"traceutil/trace.go:171","msg":"trace[1225236811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2232; }","duration":"108.943619ms","start":"2026-10-20T14:15:58.958238Z","end":"2026-10-20T14:15:59.067182Z","steps":["trace[1225236811] 'agreement among raft nodes before linearized reading' (duration: 108.760585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:15:59.06839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.402227ms","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-20T14:15:59.068471Z","caller":"traceutil/trace.go:171","msg":"trace[564003032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2233; }","duration":"109.495568ms","start":"2026-10-20T14:15:58.958958Z","end":"2026-10-20T14:15:59.068453Z","steps":["trace[564003032] 'agreement among raft nodes before linearized reading' (duration: 109.262874ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:15:59.068538Z","caller":"traceutil/trace.go:171","msg":"trace[1865286728] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"107.364878ms","start":"2026-10-20T14:15:58.96114Z","end":"2026-10-20T14:15:59.068505Z","steps":["trace[1865286728] 'process raft request' (duration: 106.996761ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:21:47.930869Z","caller":"traceutil/trace.go:171","msg":"trace[801299412] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"107.345824ms","start":"2026-10-20T14:21:47.823499Z","end":"2026-10-20T14:21:47.930845Z","steps":["trace[801299412] 'process raft request' (duration: 107.143239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:21:49.06949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.299356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:729179"} {"level":"info","ts":"2026-10-20T14:21:49.069603Z","caller":"traceutil/trace.go:171","msg":"trace[1221679631] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5189; }","duration":"132.463018ms","start":"2026-10-20T14:21:48.937119Z","end":"2026-10-20T14:21:49.069582Z","steps":["trace[1221679631] 'range keys from in-memory index tree' (duration: 131.829177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:21:51.884281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.220502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-20T14:21:51.884374Z","caller":"traceutil/trace.go:171","msg":"trace[1687811084] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"125.725401ms","start":"2026-10-20T14:21:51.758625Z","end":"2026-10-20T14:21:51.88435Z","steps":["trace[1687811084] 'compare' (duration: 115.121501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:21:53.764501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.312296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T14:21:53.764589Z","caller":"traceutil/trace.go:171","msg":"trace[1639984172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5275; }","duration":"113.433648ms","start":"2026-10-20T14:21:53.651127Z","end":"2026-10-20T14:21:53.764561Z","steps":["trace[1639984172] 'range keys from in-memory index tree' (duration: 113.131282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:22:05.817712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.580486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-86cf4f7cb6-7wnnn\" ","response":"range_response_count:1 size:8112"} {"level":"info","ts":"2026-10-20T14:22:05.817808Z","caller":"traceutil/trace.go:171","msg":"trace[627954523] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-86cf4f7cb6-7wnnn; range_end:; response_count:1; response_revision:5470; }","duration":"113.684978ms","start":"2026-10-20T14:22:05.704102Z","end":"2026-10-20T14:22:05.817787Z","steps":["trace[627954523] 'range keys from in-memory index tree' (duration: 113.206738ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:22:13.148181Z","caller":"traceutil/trace.go:171","msg":"trace[1266197611] linearizableReadLoop","detail":"{readStateIndex:5831; appliedIndex:5830; }","duration":"125.779803ms","start":"2026-10-20T14:22:13.022376Z","end":"2026-10-20T14:22:13.148156Z","steps":["trace[1266197611] 'read index received' (duration: 105.7836ms)","trace[1266197611] 'applied index is now lower than readState.Index' (duration: 19.995393ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T14:22:13.148682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.296714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1cbfcf7e-eaeb-411e-9a3e-2b3bf533de98\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-20T14:22:13.148667Z","caller":"traceutil/trace.go:171","msg":"trace[75551539] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"129.992731ms","start":"2026-10-20T14:22:13.018644Z","end":"2026-10-20T14:22:13.148636Z","steps":["trace[75551539] 'process raft request' (duration: 109.468788ms)","trace[75551539] 'compare' (duration: 19.939672ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:22:13.148728Z","caller":"traceutil/trace.go:171","msg":"trace[971717918] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1cbfcf7e-eaeb-411e-9a3e-2b3bf533de98; range_end:; response_count:1; response_revision:5620; }","duration":"125.366495ms","start":"2026-10-20T14:22:13.02335Z","end":"2026-10-20T14:22:13.148717Z","steps":["trace[971717918] 'agreement among raft nodes before linearized reading' (duration: 125.202702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:22:13.148792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.287258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hcgj8\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-20T14:22:13.148793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.053454ms","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-20T14:22:13.148863Z","caller":"traceutil/trace.go:171","msg":"trace[1893153906] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hcgj8; range_end:; response_count:1; response_revision:5620; }","duration":"115.38697ms","start":"2026-10-20T14:22:13.033458Z","end":"2026-10-20T14:22:13.148845Z","steps":["trace[1893153906] 'agreement among raft nodes before linearized reading' (duration: 115.172086ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:22:13.148865Z","caller":"traceutil/trace.go:171","msg":"trace[134767953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5620; }","duration":"122.176765ms","start":"2026-10-20T14:22:13.026675Z","end":"2026-10-20T14:22:13.148851Z","steps":["trace[134767953] 'agreement among raft nodes before linearized reading' (duration: 121.982522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:22:13.149231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.142689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-20T14:22:13.149331Z","caller":"traceutil/trace.go:171","msg":"trace[1861147189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5620; }","duration":"126.955785ms","start":"2026-10-20T14:22:13.022356Z","end":"2026-10-20T14:22:13.149312Z","steps":["trace[1861147189] 'agreement among raft nodes before linearized reading' (duration: 125.979626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:22:13.385624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.952977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-20T14:22:13.385818Z","caller":"traceutil/trace.go:171","msg":"trace[2020157815] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5622; }","duration":"110.212502ms","start":"2026-10-20T14:22:13.275577Z","end":"2026-10-20T14:22:13.385789Z","steps":["trace[2020157815] 'count revisions from in-memory index tree' (duration: 109.845616ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:22:14.993913Z","caller":"traceutil/trace.go:171","msg":"trace[1108841072] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"131.188553ms","start":"2026-10-20T14:22:14.862702Z","end":"2026-10-20T14:22:14.99389Z","steps":["trace[1108841072] 'process raft request' (duration: 131.070581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:22:27.128923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.491503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-10-20T14:22:27.129561Z","caller":"traceutil/trace.go:171","msg":"trace[1935906298] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:5809; }","duration":"136.210066ms","start":"2026-10-20T14:22:26.993329Z","end":"2026-10-20T14:22:27.129539Z","steps":["trace[1935906298] 'range keys from in-memory index tree' (duration: 135.246818ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:22:43.865914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-10-20T14:22:43.972898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"105.434544ms","hash":1921878117,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":30208000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-20T14:22:43.972991Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1921878117,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-10-20T14:27:43.877662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5922} {"level":"info","ts":"2026-10-20T14:27:43.964089Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5922,"took":"84.696222ms","hash":4158772957,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":26554368,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-20T14:27:43.964162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4158772957,"revision":5922,"compact-revision":3001} {"level":"info","ts":"2026-10-20T14:28:42.203694Z","caller":"traceutil/trace.go:171","msg":"trace[1481169655] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"215.870133ms","start":"2026-10-20T14:28:41.987803Z","end":"2026-10-20T14:28:42.203673Z","steps":["trace[1481169655] 'process raft request' (duration: 215.738621ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:28:42.205019Z","caller":"traceutil/trace.go:171","msg":"trace[624468411] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"169.616835ms","start":"2026-10-20T14:28:42.035385Z","end":"2026-10-20T14:28:42.205001Z","steps":["trace[624468411] 'process raft request' (duration: 169.474163ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:28:50.250638Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022f5.wal"} {"level":"info","ts":"2026-10-20T14:28:53.415682Z","caller":"traceutil/trace.go:171","msg":"trace[624429525] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"189.427973ms","start":"2026-10-20T14:28:53.226227Z","end":"2026-10-20T14:28:53.415655Z","steps":["trace[624429525] 'process raft request' (duration: 189.2708ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:28:53.568174Z","caller":"traceutil/trace.go:171","msg":"trace[660473274] linearizableReadLoop","detail":"{readStateIndex:8967; appliedIndex:8965; }","duration":"338.590618ms","start":"2026-10-20T14:28:53.229557Z","end":"2026-10-20T14:28:53.568148Z","steps":["trace[660473274] 'read index received' (duration: 185.959699ms)","trace[660473274] 'applied index is now lower than readState.Index' (duration: 152.629859ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T14:28:53.568306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.725471ms","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-20T14:28:53.568285Z","caller":"traceutil/trace.go:171","msg":"trace[1575918218] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"339.517325ms","start":"2026-10-20T14:28:53.228728Z","end":"2026-10-20T14:28:53.568245Z","steps":["trace[1575918218] 'process raft request' (duration: 283.988565ms)","trace[1575918218] 'compare' (duration: 55.320256ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:28:53.568343Z","caller":"traceutil/trace.go:171","msg":"trace[1694110173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8656; }","duration":"338.797143ms","start":"2026-10-20T14:28:53.229534Z","end":"2026-10-20T14:28:53.568331Z","steps":["trace[1694110173] 'agreement among raft nodes before linearized reading' (duration: 338.708671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:28:53.568373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T14:28:53.229525Z","time spent":"338.840303ms","remote":"127.0.0.1:35026","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":"warn","ts":"2026-10-20T14:28:53.56849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T14:28:53.228715Z","time spent":"339.650018ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T14:28:54.379636Z","caller":"traceutil/trace.go:171","msg":"trace[990104159] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"107.225539ms","start":"2026-10-20T14:28:54.272394Z","end":"2026-10-20T14:28:54.37962Z","steps":["trace[990104159] 'process raft request' (duration: 106.964344ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:28:54.379689Z","caller":"traceutil/trace.go:171","msg":"trace[385901701] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"107.386092ms","start":"2026-10-20T14:28:54.272269Z","end":"2026-10-20T14:28:54.379655Z","steps":["trace[385901701] 'process raft request' (duration: 78.227601ms)","trace[385901701] 'compare' (duration: 28.675772ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:28:54.379713Z","caller":"traceutil/trace.go:171","msg":"trace[490604660] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"107.2412ms","start":"2026-10-20T14:28:54.272445Z","end":"2026-10-20T14:28:54.379686Z","steps":["trace[490604660] 'process raft request' (duration: 106.954095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:29:23.134671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.457056ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-20T14:29:26.672146Z","caller":"traceutil/trace.go:171","msg":"trace[1536432171] transaction","detail":"{read_only:false; response_revision:8886; number_of_response:1; }","duration":"166.724351ms","start":"2026-10-20T14:29:26.505393Z","end":"2026-10-20T14:29:26.672118Z","steps":["trace[1536432171] 'process raft request' (duration: 125.735831ms)","trace[1536432171] 'compare' (duration: 40.810637ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:29:26.672508Z","caller":"traceutil/trace.go:171","msg":"trace[1554197910] linearizableReadLoop","detail":"{readStateIndex:9207; appliedIndex:9204; }","duration":"139.393485ms","start":"2026-10-20T14:29:26.533077Z","end":"2026-10-20T14:29:26.67247Z","steps":["trace[1554197910] 'read index received' (duration: 98.804462ms)","trace[1554197910] 'applied index is now lower than readState.Index' (duration: 40.588153ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:29:26.672527Z","caller":"traceutil/trace.go:171","msg":"trace[661428615] transaction","detail":"{read_only:false; response_revision:8887; number_of_response:1; }","duration":"151.033271ms","start":"2026-10-20T14:29:26.521431Z","end":"2026-10-20T14:29:26.672465Z","steps":["trace[661428615] 'process raft request' (duration: 150.641904ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:29:26.672655Z","caller":"traceutil/trace.go:171","msg":"trace[1810427402] transaction","detail":"{read_only:false; response_revision:8888; number_of_response:1; }","duration":"150.544482ms","start":"2026-10-20T14:29:26.522079Z","end":"2026-10-20T14:29:26.672623Z","steps":["trace[1810427402] 'process raft request' (duration: 150.242826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:29:26.67277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.437968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-20T14:29:26.672875Z","caller":"traceutil/trace.go:171","msg":"trace[719400123] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8888; }","duration":"158.583931ms","start":"2026-10-20T14:29:26.514263Z","end":"2026-10-20T14:29:26.672847Z","steps":["trace[719400123] 'agreement among raft nodes before linearized reading' (duration: 158.348266ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:29:34.779429Z","caller":"traceutil/trace.go:171","msg":"trace[461527853] linearizableReadLoop","detail":"{readStateIndex:9248; appliedIndex:9247; }","duration":"128.028604ms","start":"2026-10-20T14:29:34.65135Z","end":"2026-10-20T14:29:34.779379Z","steps":["trace[461527853] 'read index received' (duration: 127.78631ms)","trace[461527853] 'applied index is now lower than readState.Index' (duration: 241.074µs)"],"step_count":2} {"level":"warn","ts":"2026-10-20T14:29:34.779611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.234168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T14:29:34.779712Z","caller":"traceutil/trace.go:171","msg":"trace[551069951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8927; }","duration":"128.377761ms","start":"2026-10-20T14:29:34.651311Z","end":"2026-10-20T14:29:34.779688Z","steps":["trace[551069951] 'agreement among raft nodes before linearized reading' (duration: 128.218798ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:29:34.780147Z","caller":"traceutil/trace.go:171","msg":"trace[514093657] transaction","detail":"{read_only:false; response_revision:8927; number_of_response:1; }","duration":"220.899566ms","start":"2026-10-20T14:29:34.558571Z","end":"2026-10-20T14:29:34.779471Z","steps":["trace[514093657] 'process raft request' (duration: 220.59441ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:29:34.833367Z","caller":"traceutil/trace.go:171","msg":"trace[1511686574] transaction","detail":"{read_only:false; response_revision:8928; number_of_response:1; }","duration":"121.829329ms","start":"2026-10-20T14:29:34.711514Z","end":"2026-10-20T14:29:34.833344Z","steps":["trace[1511686574] 'process raft request' (duration: 97.920036ms)","trace[1511686574] 'compare' (duration: 23.74575ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:29:34.833761Z","caller":"traceutil/trace.go:171","msg":"trace[859685794] transaction","detail":"{read_only:false; response_revision:8929; number_of_response:1; }","duration":"121.85882ms","start":"2026-10-20T14:29:34.711889Z","end":"2026-10-20T14:29:34.833748Z","steps":["trace[859685794] 'process raft request' (duration: 121.413381ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:29:34.83402Z","caller":"traceutil/trace.go:171","msg":"trace[2062759104] transaction","detail":"{read_only:false; response_revision:8930; number_of_response:1; }","duration":"119.731771ms","start":"2026-10-20T14:29:34.714266Z","end":"2026-10-20T14:29:34.833997Z","steps":["trace[2062759104] 'process raft request' (duration: 119.362824ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:30:44.292389Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-20T14:30:44.299868Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-20T14:30:44.299997Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-20T14:31:04.558045Z","caller":"traceutil/trace.go:171","msg":"trace[1122439753] transaction","detail":"{read_only:false; response_revision:9789; number_of_response:1; }","duration":"165.658782ms","start":"2026-10-20T14:31:04.39236Z","end":"2026-10-20T14:31:04.558019Z","steps":["trace[1122439753] 'process raft request' (duration: 96.585872ms)","trace[1122439753] 'compare' (duration: 68.742844ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T14:31:04.812835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.51867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-20T14:31:04.812915Z","caller":"traceutil/trace.go:171","msg":"trace[1781440521] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:9789; }","duration":"144.672172ms","start":"2026-10-20T14:31:04.668225Z","end":"2026-10-20T14:31:04.812897Z","steps":["trace[1781440521] 'count revisions from in-memory index tree' (duration: 144.392668ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:32:43.886677Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8089} {"level":"info","ts":"2026-10-20T14:32:43.95759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8089,"took":"68.630414ms","hash":2916251546,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":28712960,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-20T14:32:43.957653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2916251546,"revision":8089,"compact-revision":5922} {"level":"info","ts":"2026-10-20T14:36:44.615512Z","caller":"traceutil/trace.go:171","msg":"trace[1053889534] transaction","detail":"{read_only:false; response_revision:12231; number_of_response:1; }","duration":"146.208684ms","start":"2026-10-20T14:36:44.469258Z","end":"2026-10-20T14:36:44.615467Z","steps":["trace[1053889534] 'process raft request' (duration: 106.188322ms)","trace[1053889534] 'compare' (duration: 39.583984ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:36:44.61569Z","caller":"traceutil/trace.go:171","msg":"trace[1525309455] transaction","detail":"{read_only:false; response_revision:12233; number_of_response:1; }","duration":"143.658668ms","start":"2026-10-20T14:36:44.472006Z","end":"2026-10-20T14:36:44.615665Z","steps":["trace[1525309455] 'process raft request' (duration: 143.614937ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:36:44.615848Z","caller":"traceutil/trace.go:171","msg":"trace[1303540821] transaction","detail":"{read_only:false; response_revision:12232; number_of_response:1; }","duration":"145.609513ms","start":"2026-10-20T14:36:44.470204Z","end":"2026-10-20T14:36:44.615814Z","steps":["trace[1303540821] 'process raft request' (duration: 145.087672ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:36:45.586629Z","caller":"traceutil/trace.go:171","msg":"trace[695828896] transaction","detail":"{read_only:false; response_revision:12234; number_of_response:1; }","duration":"117.751405ms","start":"2026-10-20T14:36:45.468842Z","end":"2026-10-20T14:36:45.586593Z","steps":["trace[695828896] 'process raft request' (duration: 117.46058ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:37:43.893624Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10473} {"level":"info","ts":"2026-10-20T14:37:43.983219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10473,"took":"87.30313ms","hash":1071926027,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":26558464,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-20T14:37:43.983293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1071926027,"revision":10473,"compact-revision":8089} {"level":"info","ts":"2026-10-20T14:42:43.915587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12644} {"level":"info","ts":"2026-10-20T14:42:43.999712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12644,"took":"81.618861ms","hash":1183761711,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":27918336,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-20T14:42:43.999795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1183761711,"revision":12644,"compact-revision":10473} {"level":"info","ts":"2026-10-20T14:42:55.600484Z","caller":"traceutil/trace.go:171","msg":"trace[643997289] transaction","detail":"{read_only:false; response_revision:14732; number_of_response:1; }","duration":"133.533676ms","start":"2026-10-20T14:42:55.466921Z","end":"2026-10-20T14:42:55.600455Z","steps":["trace[643997289] 'process raft request' (duration: 133.371363ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:43:33.231171Z","caller":"traceutil/trace.go:171","msg":"trace[1491925559] transaction","detail":"{read_only:false; response_revision:14940; number_of_response:1; }","duration":"129.380208ms","start":"2026-10-20T14:43:33.101763Z","end":"2026-10-20T14:43:33.231143Z","steps":["trace[1491925559] 'process raft request' (duration: 129.147834ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:43:33.940445Z","caller":"traceutil/trace.go:171","msg":"trace[1989867484] transaction","detail":"{read_only:false; response_revision:14941; number_of_response:1; }","duration":"134.412652ms","start":"2026-10-20T14:43:33.806006Z","end":"2026-10-20T14:43:33.940419Z","steps":["trace[1989867484] 'process raft request' (duration: 134.231379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:43:46.222759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.971862ms","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-20T14:43:46.222856Z","caller":"traceutil/trace.go:171","msg":"trace[1264750857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15126; }","duration":"229.129915ms","start":"2026-10-20T14:43:45.993705Z","end":"2026-10-20T14:43:46.222835Z","steps":["trace[1264750857] 'range keys from in-memory index tree' (duration: 228.749558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:43:46.222969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.221124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T14:43:46.223084Z","caller":"traceutil/trace.go:171","msg":"trace[2130913700] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:15126; }","duration":"296.387597ms","start":"2026-10-20T14:43:45.926667Z","end":"2026-10-20T14:43:46.223055Z","steps":["trace[2130913700] 'count revisions from in-memory index tree' (duration: 296.124982ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:43:46.314257Z","caller":"traceutil/trace.go:171","msg":"trace[779014844] transaction","detail":"{read_only:false; response_revision:15128; number_of_response:1; }","duration":"141.875122ms","start":"2026-10-20T14:43:46.172355Z","end":"2026-10-20T14:43:46.31423Z","steps":["trace[779014844] 'process raft request' (duration: 141.690039ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:43:46.314576Z","caller":"traceutil/trace.go:171","msg":"trace[1422838218] linearizableReadLoop","detail":"{readStateIndex:15658; appliedIndex:15654; }","duration":"139.218842ms","start":"2026-10-20T14:43:46.175345Z","end":"2026-10-20T14:43:46.314564Z","steps":["trace[1422838218] 'read index received' (duration: 72.863107ms)","trace[1422838218] 'applied index is now lower than readState.Index' (duration: 66.354875ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T14:43:46.314787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.423556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-pn8qz.18e044016f629ef6\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-10-20T14:43:46.314796Z","caller":"traceutil/trace.go:171","msg":"trace[1376219392] transaction","detail":"{read_only:false; response_revision:15129; number_of_response:1; }","duration":"142.33681ms","start":"2026-10-20T14:43:46.172439Z","end":"2026-10-20T14:43:46.314776Z","steps":["trace[1376219392] 'process raft request' (duration: 141.743679ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:43:46.314832Z","caller":"traceutil/trace.go:171","msg":"trace[347781273] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-pn8qz.18e044016f629ef6; range_end:; response_count:1; response_revision:15131; }","duration":"139.537548ms","start":"2026-10-20T14:43:46.17528Z","end":"2026-10-20T14:43:46.314818Z","steps":["trace[347781273] 'agreement among raft nodes before linearized reading' (duration: 139.370465ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:43:46.314806Z","caller":"traceutil/trace.go:171","msg":"trace[1108073787] transaction","detail":"{read_only:false; response_revision:15130; number_of_response:1; }","duration":"142.153197ms","start":"2026-10-20T14:43:46.172471Z","end":"2026-10-20T14:43:46.314624Z","steps":["trace[1108073787] 'process raft request' (duration: 142.006724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:45:27.906698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.401009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-10-20T14:45:27.906682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.870444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hcgj8\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-20T14:45:27.906764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.603284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-20T14:45:27.906812Z","caller":"traceutil/trace.go:171","msg":"trace[1352506706] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hcgj8; range_end:; response_count:1; response_revision:16224; }","duration":"190.038337ms","start":"2026-10-20T14:45:27.716754Z","end":"2026-10-20T14:45:27.906793Z","steps":["trace[1352506706] 'range keys from in-memory index tree' (duration: 189.744672ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:45:27.906814Z","caller":"traceutil/trace.go:171","msg":"trace[775274062] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16224; }","duration":"237.556092ms","start":"2026-10-20T14:45:27.669224Z","end":"2026-10-20T14:45:27.90678Z","steps":["trace[775274062] 'range keys from in-memory index tree' (duration: 237.235006ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:45:27.906856Z","caller":"traceutil/trace.go:171","msg":"trace[1715334050] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:16224; }","duration":"222.707515ms","start":"2026-10-20T14:45:27.684115Z","end":"2026-10-20T14:45:27.906822Z","steps":["trace[1715334050] 'count revisions from in-memory index tree' (duration: 222.558582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:45:27.906716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.76345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6xg52\" ","response":"range_response_count:1 size:20784"} {"level":"info","ts":"2026-10-20T14:45:27.906936Z","caller":"traceutil/trace.go:171","msg":"trace[231241552] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6xg52; range_end:; response_count:1; response_revision:16224; }","duration":"185.016165ms","start":"2026-10-20T14:45:27.721887Z","end":"2026-10-20T14:45:27.906904Z","steps":["trace[231241552] 'range keys from in-memory index tree' (duration: 184.562056ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:47:43.931406Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14633} {"level":"info","ts":"2026-10-20T14:47:44.012612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14633,"took":"77.977661ms","hash":1347238504,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":31064064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-20T14:47:44.01269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1347238504,"revision":14633,"compact-revision":12644} {"level":"info","ts":"2026-10-20T14:51:12.325292Z","caller":"traceutil/trace.go:171","msg":"trace[785175142] transaction","detail":"{read_only:false; response_revision:18811; number_of_response:1; }","duration":"269.137877ms","start":"2026-10-20T14:51:12.05612Z","end":"2026-10-20T14:51:12.325258Z","steps":["trace[785175142] 'process raft request' (duration: 262.654507ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:51:12.335921Z","caller":"traceutil/trace.go:171","msg":"trace[794122733] transaction","detail":"{read_only:false; response_revision:18812; number_of_response:1; }","duration":"260.14742ms","start":"2026-10-20T14:51:12.07573Z","end":"2026-10-20T14:51:12.335877Z","steps":["trace[794122733] 'process raft request' (duration: 259.810054ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:51:12.877549Z","caller":"traceutil/trace.go:171","msg":"trace[1669929777] transaction","detail":"{read_only:false; response_revision:18814; number_of_response:1; }","duration":"149.001972ms","start":"2026-10-20T14:51:12.728522Z","end":"2026-10-20T14:51:12.877524Z","steps":["trace[1669929777] 'process raft request' (duration: 134.263398ms)","trace[1669929777] 'compare' (duration: 14.412288ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T14:51:12.877537Z","caller":"traceutil/trace.go:171","msg":"trace[633747048] linearizableReadLoop","detail":"{readStateIndex:19463; appliedIndex:19462; }","duration":"144.202152ms","start":"2026-10-20T14:51:12.733296Z","end":"2026-10-20T14:51:12.877498Z","steps":["trace[633747048] 'read index received' (duration: 129.433428ms)","trace[633747048] 'applied index is now lower than readState.Index' (duration: 14.767594ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T14:51:12.877768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.445477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-20T14:51:12.877824Z","caller":"traceutil/trace.go:171","msg":"trace[648326807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18814; }","duration":"144.552229ms","start":"2026-10-20T14:51:12.733254Z","end":"2026-10-20T14:51:12.877806Z","steps":["trace[648326807] 'agreement among raft nodes before linearized reading' (duration: 144.344705ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:51:21.619456Z","caller":"traceutil/trace.go:171","msg":"trace[1878458223] transaction","detail":"{read_only:false; response_revision:19007; number_of_response:1; }","duration":"100.710664ms","start":"2026-10-20T14:51:21.518708Z","end":"2026-10-20T14:51:21.619419Z","steps":["trace[1878458223] 'process raft request' (duration: 100.545821ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:51:55.274744Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-20T14:51:55.297015Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-20T14:51:55.29712Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-20T14:52:43.939594Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17139} {"level":"info","ts":"2026-10-20T14:52:44.047222Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17139,"took":"104.268069ms","hash":4200125626,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":34500608,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-20T14:52:44.04731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4200125626,"revision":17139,"compact-revision":14633} {"level":"info","ts":"2026-10-20T14:57:11.841519Z","caller":"traceutil/trace.go:171","msg":"trace[1441044592] transaction","detail":"{read_only:false; response_revision:21592; number_of_response:1; }","duration":"121.614412ms","start":"2026-10-20T14:57:11.719878Z","end":"2026-10-20T14:57:11.841493Z","steps":["trace[1441044592] 'process raft request' (duration: 121.450889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T14:57:28.994623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.09124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-20T14:57:28.994723Z","caller":"traceutil/trace.go:171","msg":"trace[1219395141] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:21674; }","duration":"116.291854ms","start":"2026-10-20T14:57:28.878412Z","end":"2026-10-20T14:57:28.994704Z","steps":["trace[1219395141] 'count revisions from in-memory index tree' (duration: 115.955058ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:57:29.193417Z","caller":"traceutil/trace.go:171","msg":"trace[415078395] transaction","detail":"{read_only:false; response_revision:21678; number_of_response:1; }","duration":"195.267724ms","start":"2026-10-20T14:57:28.99812Z","end":"2026-10-20T14:57:29.193388Z","steps":["trace[415078395] 'process raft request' (duration: 195.228883ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:57:29.193468Z","caller":"traceutil/trace.go:171","msg":"trace[1209280007] transaction","detail":"{read_only:false; response_revision:21676; number_of_response:1; }","duration":"195.479588ms","start":"2026-10-20T14:57:28.997958Z","end":"2026-10-20T14:57:29.193438Z","steps":["trace[1209280007] 'process raft request' (duration: 195.172541ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:57:29.193595Z","caller":"traceutil/trace.go:171","msg":"trace[1218774114] transaction","detail":"{read_only:false; response_revision:21677; number_of_response:1; }","duration":"195.524919ms","start":"2026-10-20T14:57:28.998027Z","end":"2026-10-20T14:57:29.193552Z","steps":["trace[1218774114] 'process raft request' (duration: 195.286135ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T14:57:43.947839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19783} {"level":"info","ts":"2026-10-20T14:57:44.071191Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19783,"took":"118.966724ms","hash":3121709966,"current-db-size-bytes":57323520,"current-db-size":"57 MB","current-db-size-in-use-bytes":29917184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-20T14:57:44.071271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3121709966,"revision":19783,"compact-revision":17139} {"level":"warn","ts":"2026-10-20T15:01:39.096305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.054555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-20T15:01:39.096764Z","caller":"traceutil/trace.go:171","msg":"trace[256072453] transaction","detail":"{read_only:false; response_revision:24282; number_of_response:1; }","duration":"319.867192ms","start":"2026-10-20T15:01:38.776805Z","end":"2026-10-20T15:01:39.096672Z","steps":["trace[256072453] 'process raft request' (duration: 71.262097ms)","trace[256072453] 'compare' (duration: 247.650417ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T15:01:39.097031Z","caller":"traceutil/trace.go:171","msg":"trace[549482277] transaction","detail":"{read_only:false; response_revision:24283; number_of_response:1; }","duration":"283.354023ms","start":"2026-10-20T15:01:38.813644Z","end":"2026-10-20T15:01:39.096998Z","steps":["trace[549482277] 'process raft request' (duration: 282.919525ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T15:01:39.097098Z","caller":"traceutil/trace.go:171","msg":"trace[398672012] transaction","detail":"{read_only:false; response_revision:24284; number_of_response:1; }","duration":"193.363699ms","start":"2026-10-20T15:01:38.903706Z","end":"2026-10-20T15:01:39.09707Z","steps":["trace[398672012] 'process raft request' (duration: 193.282627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:01:39.097107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:01:38.776771Z","time spent":"320.126647ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T15:02:43.955056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21755} {"level":"info","ts":"2026-10-20T15:02:44.070636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21755,"took":"111.502064ms","hash":3780102885,"current-db-size-bytes":58593280,"current-db-size":"59 MB","current-db-size-in-use-bytes":43130880,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-20T15:02:44.070705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3780102885,"revision":21755,"compact-revision":19783} {"level":"info","ts":"2026-10-20T15:03:57.614563Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006784.wal"} {"level":"info","ts":"2026-10-20T15:05:46.2352Z","caller":"traceutil/trace.go:171","msg":"trace[2010932091] linearizableReadLoop","detail":"{readStateIndex:27530; appliedIndex:27529; }","duration":"231.873913ms","start":"2026-10-20T15:05:46.003301Z","end":"2026-10-20T15:05:46.235175Z","steps":["trace[2010932091] 'read index received' (duration: 231.668539ms)","trace[2010932091] 'applied index is now lower than readState.Index' (duration: 204.504µs)"],"step_count":2} {"level":"info","ts":"2026-10-20T15:05:46.235309Z","caller":"traceutil/trace.go:171","msg":"trace[51788961] transaction","detail":"{read_only:false; response_revision:26652; number_of_response:1; }","duration":"241.757797ms","start":"2026-10-20T15:05:45.993501Z","end":"2026-10-20T15:05:46.235259Z","steps":["trace[51788961] 'process raft request' (duration: 241.526363ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:05:46.235476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.163969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-20T15:05:46.23551Z","caller":"traceutil/trace.go:171","msg":"trace[336482698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26652; }","duration":"232.23539ms","start":"2026-10-20T15:05:46.003265Z","end":"2026-10-20T15:05:46.2355Z","steps":["trace[336482698] 'agreement among raft nodes before linearized reading' (duration: 232.069427ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T15:05:46.258378Z","caller":"traceutil/trace.go:171","msg":"trace[1203495285] transaction","detail":"{read_only:false; response_revision:26654; number_of_response:1; }","duration":"163.794553ms","start":"2026-10-20T15:05:46.09454Z","end":"2026-10-20T15:05:46.258334Z","steps":["trace[1203495285] 'process raft request' (duration: 163.64691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:05:46.258455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.979125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-20T15:05:46.258569Z","caller":"traceutil/trace.go:171","msg":"trace[1409716637] transaction","detail":"{read_only:false; response_revision:26653; number_of_response:1; }","duration":"170.797814ms","start":"2026-10-20T15:05:46.087754Z","end":"2026-10-20T15:05:46.258552Z","steps":["trace[1409716637] 'process raft request' (duration: 170.293304ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T15:05:46.258677Z","caller":"traceutil/trace.go:171","msg":"trace[1525421087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26655; }","duration":"233.071756ms","start":"2026-10-20T15:05:46.025426Z","end":"2026-10-20T15:05:46.258498Z","steps":["trace[1525421087] 'agreement among raft nodes before linearized reading' (duration: 232.848941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:05:46.258717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.396941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T15:05:46.258795Z","caller":"traceutil/trace.go:171","msg":"trace[588727309] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26655; }","duration":"204.566394ms","start":"2026-10-20T15:05:46.054209Z","end":"2026-10-20T15:05:46.258775Z","steps":["trace[588727309] 'agreement among raft nodes before linearized reading' (duration: 204.384821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:05:46.525151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.685336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-20T15:05:46.525274Z","caller":"traceutil/trace.go:171","msg":"trace[1215096604] transaction","detail":"{read_only:false; response_revision:26656; number_of_response:1; }","duration":"285.045955ms","start":"2026-10-20T15:05:46.240206Z","end":"2026-10-20T15:05:46.525252Z","steps":["trace[1215096604] 'process raft request' (duration: 146.151535ms)","trace[1215096604] 'compare' (duration: 138.232708ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T15:05:46.591675Z","caller":"traceutil/trace.go:171","msg":"trace[1548191710] transaction","detail":"{read_only:false; response_revision:26657; number_of_response:1; }","duration":"327.72822ms","start":"2026-10-20T15:05:46.263907Z","end":"2026-10-20T15:05:46.591635Z","steps":["trace[1548191710] 'process raft request' (duration: 327.354663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:05:46.591983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:05:46.263882Z","time spent":"327.981065ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T15:06:39.022306Z","caller":"traceutil/trace.go:171","msg":"trace[233881092] transaction","detail":"{read_only:false; response_revision:27141; number_of_response:1; }","duration":"236.364487ms","start":"2026-10-20T15:06:38.785915Z","end":"2026-10-20T15:06:39.02228Z","steps":["trace[233881092] 'process raft request' (duration: 236.217465ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T15:06:39.657545Z","caller":"traceutil/trace.go:171","msg":"trace[1532173231] transaction","detail":"{read_only:false; response_revision:27142; number_of_response:1; }","duration":"837.793379ms","start":"2026-10-20T15:06:38.819726Z","end":"2026-10-20T15:06:39.657519Z","steps":["trace[1532173231] 'process raft request' (duration: 837.583816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:39.657726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:38.819662Z","time spent":"837.962572ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T15:06:39.65776Z","caller":"traceutil/trace.go:171","msg":"trace[1275200192] linearizableReadLoop","detail":"{readStateIndex:28032; appliedIndex:28031; }","duration":"794.066333ms","start":"2026-10-20T15:06:38.86365Z","end":"2026-10-20T15:06:39.657717Z","steps":["trace[1275200192] 'read index received' (duration: 158.983824ms)","trace[1275200192] 'applied index is now lower than readState.Index' (duration: 635.078799ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T15:06:39.657842Z","caller":"traceutil/trace.go:171","msg":"trace[1551790599] transaction","detail":"{read_only:false; response_revision:27143; number_of_response:1; }","duration":"757.561253ms","start":"2026-10-20T15:06:38.90027Z","end":"2026-10-20T15:06:39.657831Z","steps":["trace[1551790599] 'process raft request' (duration: 757.196476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:39.657895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:38.900235Z","time spent":"757.629154ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-20T15:06:39.658031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.37665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-20T15:06:39.658076Z","caller":"traceutil/trace.go:171","msg":"trace[287087276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27143; }","duration":"794.489542ms","start":"2026-10-20T15:06:38.863572Z","end":"2026-10-20T15:06:39.658062Z","steps":["trace[287087276] 'agreement among raft nodes before linearized reading' (duration: 794.287898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:39.658108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:38.863546Z","time spent":"794.553403ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-20T15:06:39.681495Z","caller":"traceutil/trace.go:171","msg":"trace[2020192893] transaction","detail":"{read_only:false; response_revision:27144; number_of_response:1; }","duration":"286.309057ms","start":"2026-10-20T15:06:39.395157Z","end":"2026-10-20T15:06:39.681466Z","steps":["trace[2020192893] 'process raft request' (duration: 286.056182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:39.681587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.10424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T15:06:39.68171Z","caller":"traceutil/trace.go:171","msg":"trace[1602948721] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:27145; }","duration":"142.261613ms","start":"2026-10-20T15:06:39.539393Z","end":"2026-10-20T15:06:39.681655Z","steps":["trace[1602948721] 'agreement among raft nodes before linearized reading' (duration: 142.09866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:39.681615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.732392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T15:06:39.681831Z","caller":"traceutil/trace.go:171","msg":"trace[1013913371] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:27145; }","duration":"480.987607ms","start":"2026-10-20T15:06:39.200825Z","end":"2026-10-20T15:06:39.681813Z","steps":["trace[1013913371] 'agreement among raft nodes before linearized reading' (duration: 480.724893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:39.681875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:39.200809Z","time spent":"481.052488ms","remote":"127.0.0.1:50088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-10-20T15:06:39.681882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.100317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T15:06:39.681941Z","caller":"traceutil/trace.go:171","msg":"trace[735122257] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:27145; }","duration":"578.161988ms","start":"2026-10-20T15:06:39.103749Z","end":"2026-10-20T15:06:39.681911Z","steps":["trace[735122257] 'agreement among raft nodes before linearized reading' (duration: 578.072887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:39.681984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:39.10366Z","time spent":"578.312741ms","remote":"127.0.0.1:49948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"info","ts":"2026-10-20T15:06:40.053872Z","caller":"traceutil/trace.go:171","msg":"trace[261806129] transaction","detail":"{read_only:false; response_revision:27146; number_of_response:1; }","duration":"390.46881ms","start":"2026-10-20T15:06:39.663366Z","end":"2026-10-20T15:06:40.053835Z","steps":["trace[261806129] 'process raft request' (duration: 357.532436ms)","trace[261806129] 'compare' (duration: 32.387894ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T15:06:40.053888Z","caller":"traceutil/trace.go:171","msg":"trace[999370534] linearizableReadLoop","detail":"{readStateIndex:28038; appliedIndex:28035; }","duration":"372.436984ms","start":"2026-10-20T15:06:39.681424Z","end":"2026-10-20T15:06:40.053861Z","steps":["trace[999370534] 'read index received' (duration: 339.424978ms)","trace[999370534] 'applied index is now lower than readState.Index' (duration: 33.010726ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T15:06:40.053964Z","caller":"traceutil/trace.go:171","msg":"trace[1923408065] transaction","detail":"{read_only:false; response_revision:27148; number_of_response:1; }","duration":"385.814233ms","start":"2026-10-20T15:06:39.668117Z","end":"2026-10-20T15:06:40.053932Z","steps":["trace[1923408065] 'process raft request' (duration: 385.694631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:40.054025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:39.663341Z","time spent":"390.602052ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-20T15:06:40.054122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.493331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-20T15:06:40.054141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:39.668104Z","time spent":"385.950085ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T15:06:40.054165Z","caller":"traceutil/trace.go:171","msg":"trace[1824493156] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27148; }","duration":"382.597043ms","start":"2026-10-20T15:06:39.671558Z","end":"2026-10-20T15:06:40.054155Z","steps":["trace[1824493156] 'agreement among raft nodes before linearized reading' (duration: 382.43666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:40.054193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.426615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T15:06:40.054229Z","caller":"traceutil/trace.go:171","msg":"trace[1242061405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27148; }","duration":"371.495506ms","start":"2026-10-20T15:06:39.682723Z","end":"2026-10-20T15:06:40.054218Z","steps":["trace[1242061405] 'agreement among raft nodes before linearized reading' (duration: 371.431595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:40.054266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:39.682711Z","time spent":"371.548057ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-20T15:06:40.054328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.051625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-20T15:06:40.054198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:39.671536Z","time spent":"382.652115ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-20T15:06:40.054419Z","caller":"traceutil/trace.go:171","msg":"trace[645011613] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:27148; }","duration":"321.202679ms","start":"2026-10-20T15:06:39.733196Z","end":"2026-10-20T15:06:40.054398Z","steps":["trace[645011613] 'agreement among raft nodes before linearized reading' (duration: 320.996295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:40.054471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:39.733177Z","time spent":"321.28308ms","remote":"127.0.0.1:49802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-10-20T15:06:40.054522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.581442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T15:06:40.054597Z","caller":"traceutil/trace.go:171","msg":"trace[83709751] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:27148; }","duration":"367.683135ms","start":"2026-10-20T15:06:39.686889Z","end":"2026-10-20T15:06:40.054572Z","steps":["trace[83709751] 'agreement among raft nodes before linearized reading' (duration: 367.571293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:40.054588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.620276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"warn","ts":"2026-10-20T15:06:40.054651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:39.686873Z","time spent":"367.764816ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-10-20T15:06:40.054668Z","caller":"traceutil/trace.go:171","msg":"trace[1042525602] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:27148; }","duration":"196.745517ms","start":"2026-10-20T15:06:39.8579Z","end":"2026-10-20T15:06:40.054645Z","steps":["trace[1042525602] 'agreement among raft nodes before linearized reading' (duration: 196.515053ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T15:06:40.054297Z","caller":"traceutil/trace.go:171","msg":"trace[136496305] transaction","detail":"{read_only:false; response_revision:27147; number_of_response:1; }","duration":"388.600645ms","start":"2026-10-20T15:06:39.665331Z","end":"2026-10-20T15:06:40.053932Z","steps":["trace[136496305] 'process raft request' (duration: 388.391861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:40.05539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:06:39.665308Z","time spent":"389.844258ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T15:06:40.26586Z","caller":"traceutil/trace.go:171","msg":"trace[1010067010] linearizableReadLoop","detail":"{readStateIndex:28040; appliedIndex:28039; }","duration":"135.755982ms","start":"2026-10-20T15:06:40.130071Z","end":"2026-10-20T15:06:40.265827Z","steps":["trace[1010067010] 'read index received' (duration: 126.59018ms)","trace[1010067010] 'applied index is now lower than readState.Index' (duration: 9.164722ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T15:06:40.265915Z","caller":"traceutil/trace.go:171","msg":"trace[360816392] transaction","detail":"{read_only:false; response_revision:27150; number_of_response:1; }","duration":"179.073519ms","start":"2026-10-20T15:06:40.086814Z","end":"2026-10-20T15:06:40.265887Z","steps":["trace[360816392] 'process raft request' (duration: 169.921287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:40.266068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.873496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-20T15:06:40.266119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.29283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T15:06:40.266188Z","caller":"traceutil/trace.go:171","msg":"trace[672391388] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:27150; }","duration":"130.407652ms","start":"2026-10-20T15:06:40.13576Z","end":"2026-10-20T15:06:40.266168Z","steps":["trace[672391388] 'agreement among raft nodes before linearized reading' (duration: 130.30036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:06:40.266364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.635456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-20T15:06:40.266412Z","caller":"traceutil/trace.go:171","msg":"trace[261125701] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:27150; }","duration":"203.736478ms","start":"2026-10-20T15:06:40.062663Z","end":"2026-10-20T15:06:40.2664Z","steps":["trace[261125701] 'agreement among raft nodes before linearized reading' (duration: 203.587835ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T15:06:40.266619Z","caller":"traceutil/trace.go:171","msg":"trace[1401161235] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:27150; }","duration":"192.944028ms","start":"2026-10-20T15:06:40.073146Z","end":"2026-10-20T15:06:40.26609Z","steps":["trace[1401161235] 'agreement among raft nodes before linearized reading' (duration: 192.814535ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T15:07:00.750557Z","caller":"traceutil/trace.go:171","msg":"trace[1498618774] transaction","detail":"{read_only:false; response_revision:27300; number_of_response:1; }","duration":"235.851787ms","start":"2026-10-20T15:07:00.514667Z","end":"2026-10-20T15:07:00.750519Z","steps":["trace[1498618774] 'process raft request' (duration: 235.618543ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T15:07:00.86917Z","caller":"traceutil/trace.go:171","msg":"trace[299695289] linearizableReadLoop","detail":"{readStateIndex:28196; appliedIndex:28193; }","duration":"272.447249ms","start":"2026-10-20T15:07:00.596697Z","end":"2026-10-20T15:07:00.869144Z","steps":["trace[299695289] 'read index received' (duration: 153.657295ms)","trace[299695289] 'applied index is now lower than readState.Index' (duration: 118.788924ms)"],"step_count":2} {"level":"warn","ts":"2026-10-20T15:07:00.869522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.944753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T15:07:00.86957Z","caller":"traceutil/trace.go:171","msg":"trace[913252660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27302; }","duration":"218.055735ms","start":"2026-10-20T15:07:00.651502Z","end":"2026-10-20T15:07:00.869558Z","steps":["trace[913252660] 'agreement among raft nodes before linearized reading' (duration: 217.962843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:07:00.869529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.811835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-20T15:07:00.86964Z","caller":"traceutil/trace.go:171","msg":"trace[561668265] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:27302; }","duration":"273.02212ms","start":"2026-10-20T15:07:00.596592Z","end":"2026-10-20T15:07:00.869614Z","steps":["trace[561668265] 'agreement among raft nodes before linearized reading' (duration: 272.767745ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T15:07:00.869848Z","caller":"traceutil/trace.go:171","msg":"trace[835845083] transaction","detail":"{read_only:false; response_revision:27301; number_of_response:1; }","duration":"332.086091ms","start":"2026-10-20T15:07:00.537698Z","end":"2026-10-20T15:07:00.869784Z","steps":["trace[835845083] 'process raft request' (duration: 326.536609ms)"],"step_count":1} {"level":"info","ts":"2026-10-20T15:07:00.869982Z","caller":"traceutil/trace.go:171","msg":"trace[1750491156] transaction","detail":"{read_only:false; response_revision:27302; number_of_response:1; }","duration":"327.743ms","start":"2026-10-20T15:07:00.542222Z","end":"2026-10-20T15:07:00.869965Z","steps":["trace[1750491156] 'process raft request' (duration: 326.877283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-20T15:07:00.870108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:07:00.537644Z","time spent":"332.320846ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-20T15:07:00.870128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-20T15:07:00.542192Z","time spent":"327.833542ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-20T15:07:43.961017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24903} {"level":"info","ts":"2026-10-20T15:07:44.115213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24903,"took":"150.072708ms","hash":1027343382,"current-db-size-bytes":60280832,"current-db-size":"60 MB","current-db-size-in-use-bytes":36356096,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-20T15:07:44.115291Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1027343382,"revision":24903,"compact-revision":21755} {"level":"info","ts":"2026-10-20T15:10:03.862202Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-20T15:10:03.867812Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-20T15:10:03.867902Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-20T15:11:07.911428Z","caller":"traceutil/trace.go:171","msg":"trace[232364167] transaction","detail":"{read_only:false; response_revision:29765; number_of_response:1; }","duration":"100.30325ms","start":"2026-10-20T15:11:07.811093Z","end":"2026-10-20T15:11:07.911396Z","steps":["trace[232364167] 'process raft request' (duration: 15.214394ms)","trace[232364167] 'compare' (duration: 84.886223ms)"],"step_count":2} {"level":"info","ts":"2026-10-20T15:12:43.968104Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27793} {"level":"info","ts":"2026-10-20T15:12:44.121538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27793,"took":"149.093113ms","hash":818659702,"current-db-size-bytes":60280832,"current-db-size":"60 MB","current-db-size-in-use-bytes":35426304,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-20T15:12:44.12169Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":818659702,"revision":27793,"compact-revision":24903} {"level":"info","ts":"2026-10-20T15:17:43.974828Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30490} {"level":"info","ts":"2026-10-20T15:17:44.130925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30490,"took":"151.833293ms","hash":1207923678,"current-db-size-bytes":60280832,"current-db-size":"60 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-20T15:17:44.131025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1207923678,"revision":30490,"compact-revision":27793} {"level":"info","ts":"2026-10-20T15:22:43.981858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32742} {"level":"info","ts":"2026-10-20T15:22:44.153111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32742,"took":"166.202632ms","hash":929729483,"current-db-size-bytes":61321216,"current-db-size":"61 MB","current-db-size-in-use-bytes":30433280,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-20T15:22:44.153186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":929729483,"revision":32742,"compact-revision":30490} {"level":"info","ts":"2026-10-20T15:27:44.00993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34967} {"level":"info","ts":"2026-10-20T15:27:44.147691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34967,"took":"133.519223ms","hash":2174985407,"current-db-size-bytes":61321216,"current-db-size":"61 MB","current-db-size-in-use-bytes":31506432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-20T15:27:44.147759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2174985407,"revision":34967,"compact-revision":32742} {"level":"info","ts":"2026-10-20T15:31:02.456866Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-20T15:31:02.463453Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-20T15:31:02.463543Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-20T15:32:44.017086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37265} {"level":"info","ts":"2026-10-20T15:32:44.159635Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37265,"took":"138.105121ms","hash":1307556957,"current-db-size-bytes":61321216,"current-db-size":"61 MB","current-db-size-in-use-bytes":33304576,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-20T15:32:44.159712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1307556957,"revision":37265,"compact-revision":34967} {"level":"info","ts":"2026-10-20T15:37:44.024507Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39536} {"level":"info","ts":"2026-10-20T15:37:44.16068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39536,"took":"132.257451ms","hash":307528065,"current-db-size-bytes":61747200,"current-db-size":"62 MB","current-db-size-in-use-bytes":32256000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-20T15:37:44.160763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":307528065,"revision":39536,"compact-revision":37265} {"level":"info","ts":"2026-10-20T15:42:44.041124Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41772} {"level":"info","ts":"2026-10-20T15:42:44.17006Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41772,"took":"122.820696ms","hash":2521525708,"current-db-size-bytes":61747200,"current-db-size":"62 MB","current-db-size-in-use-bytes":30638080,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-20T15:42:44.170122Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2521525708,"revision":41772,"compact-revision":39536}