{"level":"warn","ts":"2026-10-26T19:07:48.422924Z","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-26T19:07:48.423296Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.93: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.93:2380","--initial-cluster=instance=https://162.253.55.93:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.93:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.93: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-26T19:07:48.423378Z","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-26T19:07:48.423386Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-10-26T19:07:48.423402Z","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-26T19:07:48.425577Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"]} {"level":"info","ts":"2026-10-26T19:07:48.425884Z","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":8,"max-cpu-available":8,"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.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.93: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-26T19:07:48.432158Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.036328ms"} {"level":"info","ts":"2026-10-26T19:07:48.441121Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ea06c5748459930b","cluster-id":"49d4e1d71c75bc5"} {"level":"info","ts":"2026-10-26T19:07:48.441175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T19:07:48.441192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 0"} {"level":"info","ts":"2026-10-26T19:07:48.441202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ea06c5748459930b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T19:07:48.44121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 1"} {"level":"info","ts":"2026-10-26T19:07:48.441243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"warn","ts":"2026-10-26T19:07:48.448041Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T19:07:48.450594Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T19:07:48.451911Z","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-26T19:07:48.453929Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ea06c5748459930b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T19:07:48.455412Z","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-26T19:07:48.45548Z","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-26T19:07:48.455487Z","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-26T19:07:48.455414Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ea06c5748459930b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T19:07:48.456163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"info","ts":"2026-10-26T19:07:48.45654Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","added-peer-id":"ea06c5748459930b","added-peer-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-10-26T19:07:48.458286Z","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-26T19:07:48.458508Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ea06c5748459930b","initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T19:07:48.45855Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T19:07:48.458667Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-10-26T19:07:48.458695Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-10-26T19:07:48.641604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T19:07:48.641687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T19:07:48.641715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgPreVoteResp from ea06c5748459930b at term 1"} {"level":"info","ts":"2026-10-26T19:07:48.641733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became candidate at term 2"} {"level":"info","ts":"2026-10-26T19:07:48.641741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgVoteResp from ea06c5748459930b at term 2"} {"level":"info","ts":"2026-10-26T19:07:48.641757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became leader at term 2"} {"level":"info","ts":"2026-10-26T19:07:48.641768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ea06c5748459930b elected leader ea06c5748459930b at term 2"} {"level":"info","ts":"2026-10-26T19:07:48.645066Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:07:48.645943Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ea06c5748459930b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.93:2379]}","request-path":"/0/members/ea06c5748459930b/attributes","cluster-id":"49d4e1d71c75bc5","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T19:07:48.64619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:07:48.646255Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:07:48.646667Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:07:48.646758Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:07:48.646779Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:07:48.64681Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T19:07:48.646826Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T19:07:48.649413Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T19:07:48.650844Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.93:2379"} {"level":"info","ts":"2026-10-26T19:08:19.427519Z","caller":"traceutil/trace.go:171","msg":"trace[653295787] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"298.918097ms","start":"2026-10-26T19:08:19.12858Z","end":"2026-10-26T19:08:19.427498Z","steps":["trace[653295787] 'process raft request' (duration: 298.807205ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:35.6217Z","caller":"traceutil/trace.go:171","msg":"trace[1695815133] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"147.363376ms","start":"2026-10-26T19:08:35.474309Z","end":"2026-10-26T19:08:35.621672Z","steps":["trace[1695815133] 'process raft request' (duration: 145.321265ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:35.64735Z","caller":"traceutil/trace.go:171","msg":"trace[639503612] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"172.874963ms","start":"2026-10-26T19:08:35.474429Z","end":"2026-10-26T19:08:35.647304Z","steps":["trace[639503612] 'process raft request' (duration: 172.611859ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:35.647599Z","caller":"traceutil/trace.go:171","msg":"trace[2114001697] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"161.050574ms","start":"2026-10-26T19:08:35.486521Z","end":"2026-10-26T19:08:35.647572Z","steps":["trace[2114001697] 'process raft request' (duration: 160.696048ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:35.647704Z","caller":"traceutil/trace.go:171","msg":"trace[759579302] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"154.762968ms","start":"2026-10-26T19:08:35.492904Z","end":"2026-10-26T19:08:35.647667Z","steps":["trace[759579302] 'process raft request' (duration: 154.380802ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:35.648109Z","caller":"traceutil/trace.go:171","msg":"trace[499600753] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"143.208113ms","start":"2026-10-26T19:08:35.504894Z","end":"2026-10-26T19:08:35.648102Z","steps":["trace[499600753] 'process raft request' (duration: 142.727125ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:35.648265Z","caller":"traceutil/trace.go:171","msg":"trace[295468327] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"139.151951ms","start":"2026-10-26T19:08:35.509107Z","end":"2026-10-26T19:08:35.648258Z","steps":["trace[295468327] 'process raft request' (duration: 138.896177ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:35.650518Z","caller":"traceutil/trace.go:171","msg":"trace[1201886548] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"103.432ms","start":"2026-10-26T19:08:35.547035Z","end":"2026-10-26T19:08:35.650467Z","steps":["trace[1201886548] 'process raft request' (duration: 102.598617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:08:35.65109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.683505ms","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-26T19:08:35.651423Z","caller":"traceutil/trace.go:171","msg":"trace[1822856497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"116.080001ms","start":"2026-10-26T19:08:35.535301Z","end":"2026-10-26T19:08:35.651381Z","steps":["trace[1822856497] 'agreement among raft nodes before linearized reading' (duration: 115.239959ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:35.65051Z","caller":"traceutil/trace.go:171","msg":"trace[1061333080] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:577; }","duration":"115.036225ms","start":"2026-10-26T19:08:35.53535Z","end":"2026-10-26T19:08:35.650386Z","steps":["trace[1061333080] 'read index received' (duration: 84.568333ms)","trace[1061333080] 'applied index is now lower than readState.Index' (duration: 30.465252ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:08:35.652494Z","caller":"traceutil/trace.go:171","msg":"trace[1298609538] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"132.074324ms","start":"2026-10-26T19:08:35.520395Z","end":"2026-10-26T19:08:35.65247Z","steps":["trace[1298609538] 'process raft request' (duration: 128.004312ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:35.652774Z","caller":"traceutil/trace.go:171","msg":"trace[2110723157] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"137.942983ms","start":"2026-10-26T19:08:35.511205Z","end":"2026-10-26T19:08:35.649148Z","steps":["trace[2110723157] 'process raft request' (duration: 137.019389ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:42.396104Z","caller":"traceutil/trace.go:171","msg":"trace[880198111] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"315.348813ms","start":"2026-10-26T19:08:42.080711Z","end":"2026-10-26T19:08:42.39606Z","steps":["trace[880198111] 'process raft request' (duration: 314.681083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:08:42.397187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:08:42.080683Z","time spent":"315.529556ms","remote":"127.0.0.1:45938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:08:42.948131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.600216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:08:42.948254Z","caller":"traceutil/trace.go:171","msg":"trace[390496118] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"362.525945ms","start":"2026-10-26T19:08:42.585707Z","end":"2026-10-26T19:08:42.948232Z","steps":["trace[390496118] 'read index received' (duration: 259.548092ms)","trace[390496118] 'applied index is now lower than readState.Index' (duration: 102.976542ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:08:42.948537Z","caller":"traceutil/trace.go:171","msg":"trace[78082592] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"474.164687ms","start":"2026-10-26T19:08:42.474327Z","end":"2026-10-26T19:08:42.948492Z","steps":["trace[78082592] 'process raft request' (duration: 370.907321ms)","trace[78082592] 'compare' (duration: 102.236461ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:08:42.948606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.511075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.93\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-10-26T19:08:42.948659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.92737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-djrd2\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-26T19:08:42.948677Z","caller":"traceutil/trace.go:171","msg":"trace[602138539] range","detail":"{range_begin:/registry/masterleases/162.253.55.93; range_end:; response_count:1; response_revision:654; }","duration":"295.670707ms","start":"2026-10-26T19:08:42.652984Z","end":"2026-10-26T19:08:42.948655Z","steps":["trace[602138539] 'agreement among raft nodes before linearized reading' (duration: 295.516125ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:42.948726Z","caller":"traceutil/trace.go:171","msg":"trace[1763169403] range","detail":"{range_begin:/registry/pods/kube-system/cilium-djrd2; range_end:; response_count:1; response_revision:654; }","duration":"363.078763ms","start":"2026-10-26T19:08:42.58563Z","end":"2026-10-26T19:08:42.948709Z","steps":["trace[1763169403] 'agreement among raft nodes before linearized reading' (duration: 362.732987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:08:42.948735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:08:42.474299Z","time spent":"474.34472ms","remote":"127.0.0.1:46340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T19:08:42.94877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:08:42.5856Z","time spent":"363.159434ms","remote":"127.0.0.1:46012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-djrd2\" "} {"level":"warn","ts":"2026-10-26T19:08:42.948766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.998101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-26T19:08:42.948848Z","caller":"traceutil/trace.go:171","msg":"trace[355283903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"290.126303ms","start":"2026-10-26T19:08:42.658696Z","end":"2026-10-26T19:08:42.948822Z","steps":["trace[355283903] 'agreement among raft nodes before linearized reading' (duration: 289.853869ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:08:43.058166Z","caller":"traceutil/trace.go:171","msg":"trace[1640492840] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"103.167315ms","start":"2026-10-26T19:08:42.954982Z","end":"2026-10-26T19:08:43.05815Z","steps":["trace[1640492840] 'process raft request' (duration: 94.951071ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:09:53.167918Z","caller":"traceutil/trace.go:171","msg":"trace[119783147] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"121.801088ms","start":"2026-10-26T19:09:53.046085Z","end":"2026-10-26T19:09:53.167886Z","steps":["trace[119783147] 'process raft request' (duration: 121.499408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:10:21.673284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.701327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:10:21.673419Z","caller":"traceutil/trace.go:171","msg":"trace[1796278553] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"306.23307ms","start":"2026-10-26T19:10:21.367167Z","end":"2026-10-26T19:10:21.6734Z","steps":["trace[1796278553] 'read index received' (duration: 4.265675ms)","trace[1796278553] 'applied index is now lower than readState.Index' (duration: 301.966085ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:10:21.673449Z","caller":"traceutil/trace.go:171","msg":"trace[196796964] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"333.38735ms","start":"2026-10-26T19:10:21.340032Z","end":"2026-10-26T19:10:21.673419Z","steps":["trace[196796964] 'process raft request' (duration: 31.461016ms)","trace[196796964] 'compare' (duration: 301.599873ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:10:21.673597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.405345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:10:21.673628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:10:21.339996Z","time spent":"333.558315ms","remote":"127.0.0.1:46118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:10:21.673627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.451987ms","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-26T19:10:21.673667Z","caller":"traceutil/trace.go:171","msg":"trace[25889341] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1916; }","duration":"306.519229ms","start":"2026-10-26T19:10:21.367128Z","end":"2026-10-26T19:10:21.673647Z","steps":["trace[25889341] 'agreement among raft nodes before linearized reading' (duration: 306.365884ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:10:21.673676Z","caller":"traceutil/trace.go:171","msg":"trace[62196114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1916; }","duration":"306.53406ms","start":"2026-10-26T19:10:21.367129Z","end":"2026-10-26T19:10:21.673663Z","steps":["trace[62196114] 'agreement among raft nodes before linearized reading' (duration: 306.325983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:10:21.673715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:10:21.367109Z","time spent":"306.592702ms","remote":"127.0.0.1:48580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-10-26T19:10:21.673727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:10:21.367117Z","time spent":"306.585242ms","remote":"127.0.0.1:46118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T19:10:21.829557Z","caller":"traceutil/trace.go:171","msg":"trace[1845345973] linearizableReadLoop","detail":"{readStateIndex:1989; appliedIndex:1988; }","duration":"132.186533ms","start":"2026-10-26T19:10:21.697326Z","end":"2026-10-26T19:10:21.829513Z","steps":["trace[1845345973] 'read index received' (duration: 111.473068ms)","trace[1845345973] 'applied index is now lower than readState.Index' (duration: 20.712575ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:10:21.829644Z","caller":"traceutil/trace.go:171","msg":"trace[434430874] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"151.451293ms","start":"2026-10-26T19:10:21.678153Z","end":"2026-10-26T19:10:21.829605Z","steps":["trace[434430874] 'process raft request' (duration: 130.698206ms)","trace[434430874] 'compare' (duration: 20.565741ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:10:21.829856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.3889ms","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-26T19:10:21.8299Z","caller":"traceutil/trace.go:171","msg":"trace[601862345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1918; }","duration":"132.589775ms","start":"2026-10-26T19:10:21.697299Z","end":"2026-10-26T19:10:21.829889Z","steps":["trace[601862345] 'agreement among raft nodes before linearized reading' (duration: 132.324247ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:10:34.517348Z","caller":"traceutil/trace.go:171","msg":"trace[234262138] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"150.695922ms","start":"2026-10-26T19:10:34.366627Z","end":"2026-10-26T19:10:34.517323Z","steps":["trace[234262138] 'process raft request' (duration: 55.224368ms)","trace[234262138] 'compare' (duration: 94.948458ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:10:34.51746Z","caller":"traceutil/trace.go:171","msg":"trace[11053011] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"100.273739ms","start":"2026-10-26T19:10:34.417159Z","end":"2026-10-26T19:10:34.517433Z","steps":["trace[11053011] 'process raft request' (duration: 99.898108ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:10:34.517459Z","caller":"traceutil/trace.go:171","msg":"trace[993265172] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"150.613559ms","start":"2026-10-26T19:10:34.366787Z","end":"2026-10-26T19:10:34.517401Z","steps":["trace[993265172] 'process raft request' (duration: 150.155405ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:10:34.765405Z","caller":"traceutil/trace.go:171","msg":"trace[2023076139] linearizableReadLoop","detail":"{readStateIndex:2108; appliedIndex:2106; }","duration":"208.683813ms","start":"2026-10-26T19:10:34.556691Z","end":"2026-10-26T19:10:34.765375Z","steps":["trace[2023076139] 'read index received' (duration: 35.261735ms)","trace[2023076139] 'applied index is now lower than readState.Index' (duration: 173.420888ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:10:34.765696Z","caller":"traceutil/trace.go:171","msg":"trace[962605052] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"237.252626ms","start":"2026-10-26T19:10:34.528394Z","end":"2026-10-26T19:10:34.765647Z","steps":["trace[962605052] 'process raft request' (duration: 145.787933ms)","trace[962605052] 'compare' (duration: 91.063131ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:10:34.76616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.443495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-26T19:10:34.766223Z","caller":"traceutil/trace.go:171","msg":"trace[1608583823] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2033; }","duration":"209.58007ms","start":"2026-10-26T19:10:34.556624Z","end":"2026-10-26T19:10:34.766204Z","steps":["trace[1608583823] 'agreement among raft nodes before linearized reading' (duration: 209.220409ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:10:37.947425Z","caller":"traceutil/trace.go:171","msg":"trace[1603346146] linearizableReadLoop","detail":"{readStateIndex:2123; appliedIndex:2122; }","duration":"173.687271ms","start":"2026-10-26T19:10:37.77372Z","end":"2026-10-26T19:10:37.947407Z","steps":["trace[1603346146] 'read index received' (duration: 173.515406ms)","trace[1603346146] 'applied index is now lower than readState.Index' (duration: 170.505µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:10:37.947526Z","caller":"traceutil/trace.go:171","msg":"trace[972886608] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"180.774804ms","start":"2026-10-26T19:10:37.766744Z","end":"2026-10-26T19:10:37.947518Z","steps":["trace[972886608] 'process raft request' (duration: 180.505476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:10:37.947842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.101224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-26T19:10:37.947929Z","caller":"traceutil/trace.go:171","msg":"trace[1595870843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2048; }","duration":"174.209637ms","start":"2026-10-26T19:10:37.773697Z","end":"2026-10-26T19:10:37.947906Z","steps":["trace[1595870843] 'agreement among raft nodes before linearized reading' (duration: 174.044742ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:11:06.218372Z","caller":"traceutil/trace.go:171","msg":"trace[1446315125] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"116.034449ms","start":"2026-10-26T19:11:06.102308Z","end":"2026-10-26T19:11:06.218342Z","steps":["trace[1446315125] 'process raft request' (duration: 112.565385ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:11:06.221592Z","caller":"traceutil/trace.go:171","msg":"trace[1218617598] linearizableReadLoop","detail":"{readStateIndex:2290; appliedIndex:2287; }","duration":"110.657573ms","start":"2026-10-26T19:11:06.110908Z","end":"2026-10-26T19:11:06.221566Z","steps":["trace[1218617598] 'read index received' (duration: 103.978351ms)","trace[1218617598] 'applied index is now lower than readState.Index' (duration: 6.677502ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:11:06.221763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.845217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:11:06.221802Z","caller":"traceutil/trace.go:171","msg":"trace[99532634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2208; }","duration":"110.93803ms","start":"2026-10-26T19:11:06.110852Z","end":"2026-10-26T19:11:06.22179Z","steps":["trace[99532634] 'agreement among raft nodes before linearized reading' (duration: 110.812047ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:11:06.222264Z","caller":"traceutil/trace.go:171","msg":"trace[286939525] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"117.471048ms","start":"2026-10-26T19:11:06.104781Z","end":"2026-10-26T19:11:06.222252Z","steps":["trace[286939525] 'process raft request' (duration: 116.548053ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:11:06.222447Z","caller":"traceutil/trace.go:171","msg":"trace[1343155114] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"117.348574ms","start":"2026-10-26T19:11:06.105077Z","end":"2026-10-26T19:11:06.222426Z","steps":["trace[1343155114] 'process raft request' (duration: 116.410869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:11:06.567301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.287712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-26T19:11:06.567398Z","caller":"traceutil/trace.go:171","msg":"trace[307657200] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2208; }","duration":"229.451436ms","start":"2026-10-26T19:11:06.337925Z","end":"2026-10-26T19:11:06.567377Z","steps":["trace[307657200] 'range keys from in-memory index tree' (duration: 228.978864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:11:53.318783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.578449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:11:53.318861Z","caller":"traceutil/trace.go:171","msg":"trace[2061270123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2561; }","duration":"206.681571ms","start":"2026-10-26T19:11:53.112161Z","end":"2026-10-26T19:11:53.318843Z","steps":["trace[2061270123] 'range keys from in-memory index tree' (duration: 206.535978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:11:53.319104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.102185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-26T19:11:53.31916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.819584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-26T19:11:53.31919Z","caller":"traceutil/trace.go:171","msg":"trace[1845080462] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2561; }","duration":"208.232398ms","start":"2026-10-26T19:11:53.110937Z","end":"2026-10-26T19:11:53.319169Z","steps":["trace[1845080462] 'count revisions from in-memory index tree' (duration: 208.034924ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:11:53.319251Z","caller":"traceutil/trace.go:171","msg":"trace[690274987] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2561; }","duration":"146.938537ms","start":"2026-10-26T19:11:53.172273Z","end":"2026-10-26T19:11:53.319212Z","steps":["trace[690274987] 'range keys from in-memory index tree' (duration: 146.61638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:12:24.91653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.213354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:12:24.916619Z","caller":"traceutil/trace.go:171","msg":"trace[1266283366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2734; }","duration":"237.338627ms","start":"2026-10-26T19:12:24.679259Z","end":"2026-10-26T19:12:24.916598Z","steps":["trace[1266283366] 'range keys from in-memory index tree' (duration: 237.073662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:12:24.916869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.455253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-26T19:12:24.916988Z","caller":"traceutil/trace.go:171","msg":"trace[1690795891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2734; }","duration":"177.603407ms","start":"2026-10-26T19:12:24.739366Z","end":"2026-10-26T19:12:24.91697Z","steps":["trace[1690795891] 'range keys from in-memory index tree' (duration: 177.32005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:12:24.916995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.349951ms","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-26T19:12:24.917305Z","caller":"traceutil/trace.go:171","msg":"trace[900841619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2734; }","duration":"169.677579ms","start":"2026-10-26T19:12:24.747611Z","end":"2026-10-26T19:12:24.917288Z","steps":["trace[900841619] 'range keys from in-memory index tree' (duration: 169.211738ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:15:32.588907Z","caller":"traceutil/trace.go:171","msg":"trace[1946319352] transaction","detail":"{read_only:false; response_revision:3798; number_of_response:1; }","duration":"137.259331ms","start":"2026-10-26T19:15:32.451625Z","end":"2026-10-26T19:15:32.588885Z","steps":["trace[1946319352] 'process raft request' (duration: 136.933625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:16:57.415998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:16:57.416173Z","caller":"traceutil/trace.go:171","msg":"trace[138922446] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"393.022527ms","start":"2026-10-26T19:16:57.023131Z","end":"2026-10-26T19:16:57.416154Z","steps":["trace[138922446] 'process raft request' (duration: 248.923603ms)","trace[138922446] 'compare' (duration: 143.25855ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:16:57.416243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:16:57.023105Z","time spent":"393.108238ms","remote":"127.0.0.1:46132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1697,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:16:57.416577Z","caller":"traceutil/trace.go:171","msg":"trace[1397244262] linearizableReadLoop","detail":"{readStateIndex:4877; appliedIndex:4875; }","duration":"378.909771ms","start":"2026-10-26T19:16:57.037637Z","end":"2026-10-26T19:16:57.416547Z","steps":["trace[1397244262] 'read index received' (duration: 234.39499ms)","trace[1397244262] 'applied index is now lower than readState.Index' (duration: 144.513681ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:16:57.41667Z","caller":"traceutil/trace.go:171","msg":"trace[1462601288] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"393.076698ms","start":"2026-10-26T19:16:57.023581Z","end":"2026-10-26T19:16:57.416657Z","steps":["trace[1462601288] 'process raft request' (duration: 392.520928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:16:57.416728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:16:57.023555Z","time spent":"393.142719ms","remote":"127.0.0.1:45998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1248,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:16:57.416736Z","caller":"traceutil/trace.go:171","msg":"trace[1167711289] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"174.772169ms","start":"2026-10-26T19:16:57.241936Z","end":"2026-10-26T19:16:57.416708Z","steps":["trace[1167711289] 'process raft request' (duration: 174.492214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:16:57.416812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.155475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-26T19:16:57.416881Z","caller":"traceutil/trace.go:171","msg":"trace[1553121779] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4700; }","duration":"379.244306ms","start":"2026-10-26T19:16:57.037593Z","end":"2026-10-26T19:16:57.416837Z","steps":["trace[1553121779] 'agreement among raft nodes before linearized reading' (duration: 379.098154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:16:57.416921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:16:57.037575Z","time spent":"379.332159ms","remote":"127.0.0.1:46118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-26T19:16:57.416914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.494811ms","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-26T19:16:57.417006Z","caller":"traceutil/trace.go:171","msg":"trace[1571847443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4700; }","duration":"375.629454ms","start":"2026-10-26T19:16:57.041351Z","end":"2026-10-26T19:16:57.41698Z","steps":["trace[1571847443] 'agreement among raft nodes before linearized reading' (duration: 375.352759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:16:57.417041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.055467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:16:57.417056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:16:57.041335Z","time spent":"375.707985ms","remote":"127.0.0.1:46118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T19:16:57.417076Z","caller":"traceutil/trace.go:171","msg":"trace[1910800256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4700; }","duration":"307.113948ms","start":"2026-10-26T19:16:57.109952Z","end":"2026-10-26T19:16:57.417065Z","steps":["trace[1910800256] 'agreement among raft nodes before linearized reading' (duration: 307.069657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:16:57.417075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.235886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-10-26T19:16:57.417103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:16:57.109911Z","time spent":"307.186549ms","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T19:16:57.417115Z","caller":"traceutil/trace.go:171","msg":"trace[1105788333] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4700; }","duration":"101.339278ms","start":"2026-10-26T19:16:57.315764Z","end":"2026-10-26T19:16:57.417103Z","steps":["trace[1105788333] 'agreement among raft nodes before linearized reading' (duration: 101.209836ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:17:04.25662Z","caller":"traceutil/trace.go:171","msg":"trace[1111214148] linearizableReadLoop","detail":"{readStateIndex:5086; appliedIndex:5085; }","duration":"149.854509ms","start":"2026-10-26T19:17:04.106745Z","end":"2026-10-26T19:17:04.256599Z","steps":["trace[1111214148] 'read index received' (duration: 149.672396ms)","trace[1111214148] 'applied index is now lower than readState.Index' (duration: 181.133µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:17:04.256727Z","caller":"traceutil/trace.go:171","msg":"trace[875893562] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"185.175994ms","start":"2026-10-26T19:17:04.071541Z","end":"2026-10-26T19:17:04.256717Z","steps":["trace[875893562] 'process raft request' (duration: 184.92736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:17:04.256976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.215845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:17:04.25703Z","caller":"traceutil/trace.go:171","msg":"trace[1328233034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4902; }","duration":"150.303717ms","start":"2026-10-26T19:17:04.106714Z","end":"2026-10-26T19:17:04.257017Z","steps":["trace[1328233034] 'agreement among raft nodes before linearized reading' (duration: 150.229576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:17:08.874885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.130359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:17:08.875003Z","caller":"traceutil/trace.go:171","msg":"trace[1258206357] linearizableReadLoop","detail":"{readStateIndex:5178; appliedIndex:5177; }","duration":"285.882221ms","start":"2026-10-26T19:17:08.589102Z","end":"2026-10-26T19:17:08.874984Z","steps":["trace[1258206357] 'read index received' (duration: 127.519738ms)","trace[1258206357] 'applied index is now lower than readState.Index' (duration: 158.360863ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:17:08.875219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.116435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eb7a3c10-4568-4231-b479-adb943c6b64b\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-26T19:17:08.875277Z","caller":"traceutil/trace.go:171","msg":"trace[144885439] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eb7a3c10-4568-4231-b479-adb943c6b64b; range_end:; response_count:1; response_revision:4992; }","duration":"286.221857ms","start":"2026-10-26T19:17:08.589039Z","end":"2026-10-26T19:17:08.875261Z","steps":["trace[144885439] 'agreement among raft nodes before linearized reading' (duration: 286.018194ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:17:08.875453Z","caller":"traceutil/trace.go:171","msg":"trace[665267059] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"316.899621ms","start":"2026-10-26T19:17:08.558519Z","end":"2026-10-26T19:17:08.875419Z","steps":["trace[665267059] 'process raft request' (duration: 158.144171ms)","trace[665267059] 'compare' (duration: 157.797813ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:17:08.875627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:17:08.558507Z","time spent":"317.039153ms","remote":"127.0.0.1:46118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:17:09.243486Z","caller":"traceutil/trace.go:171","msg":"trace[1214376472] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"130.398947ms","start":"2026-10-26T19:17:09.113061Z","end":"2026-10-26T19:17:09.24346Z","steps":["trace[1214376472] 'process raft request' (duration: 122.310776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:17:09.243551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.406575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:17:09.243623Z","caller":"traceutil/trace.go:171","msg":"trace[54271418] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4994; }","duration":"108.531207ms","start":"2026-10-26T19:17:09.135074Z","end":"2026-10-26T19:17:09.243605Z","steps":["trace[54271418] 'agreement among raft nodes before linearized reading' (duration: 108.384655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:17:10.460601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.919164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:17:10.460691Z","caller":"traceutil/trace.go:171","msg":"trace[1798476980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5008; }","duration":"105.066287ms","start":"2026-10-26T19:17:10.355606Z","end":"2026-10-26T19:17:10.460672Z","steps":["trace[1798476980] 'range keys from in-memory index tree' (duration: 104.467516ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:17:21.820139Z","caller":"traceutil/trace.go:171","msg":"trace[621391861] linearizableReadLoop","detail":"{readStateIndex:5341; appliedIndex:5340; }","duration":"125.439495ms","start":"2026-10-26T19:17:21.694669Z","end":"2026-10-26T19:17:21.820108Z","steps":["trace[621391861] 'read index received' (duration: 66.436992ms)","trace[621391861] 'applied index is now lower than readState.Index' (duration: 59.001473ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:17:21.820529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.837252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-26T19:17:21.820609Z","caller":"traceutil/trace.go:171","msg":"trace[387849572] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5150; }","duration":"125.929233ms","start":"2026-10-26T19:17:21.69466Z","end":"2026-10-26T19:17:21.820589Z","steps":["trace[387849572] 'agreement among raft nodes before linearized reading' (duration: 125.700369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:17:21.820844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.931459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-26T19:17:21.820368Z","caller":"traceutil/trace.go:171","msg":"trace[303858116] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"184.449537ms","start":"2026-10-26T19:17:21.63589Z","end":"2026-10-26T19:17:21.820339Z","steps":["trace[303858116] 'process raft request' (duration: 125.275321ms)","trace[303858116] 'compare' (duration: 58.83517ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:17:21.821129Z","caller":"traceutil/trace.go:171","msg":"trace[1087168613] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5150; }","duration":"120.180623ms","start":"2026-10-26T19:17:21.70083Z","end":"2026-10-26T19:17:21.82101Z","steps":["trace[1087168613] 'agreement among raft nodes before linearized reading' (duration: 119.694825ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:17:48.873429Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2861} {"level":"info","ts":"2026-10-26T19:17:48.945172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2861,"took":"70.570336ms","hash":2145768598,"current-db-size-bytes":53309440,"current-db-size":"53 MB","current-db-size-in-use-bytes":28962816,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T19:17:48.94525Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2145768598,"revision":2861,"compact-revision":-1} {"level":"info","ts":"2026-10-26T19:22:48.880112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5447} {"level":"info","ts":"2026-10-26T19:22:48.962886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5447,"took":"80.924634ms","hash":3665559229,"current-db-size-bytes":53309440,"current-db-size":"53 MB","current-db-size-in-use-bytes":18997248,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-26T19:22:48.962951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3665559229,"revision":5447,"compact-revision":2861}