{"level":"warn","ts":"2026-03-29T01:55:46.370069Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-29T01:55:46.370186Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-03-29T01:55:46.370288Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-29T01:55:46.370313Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-03-29T01:55:46.370351Z","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-03-29T01:55:46.371444Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-03-29T01:55:46.371603Z","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.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-03-29T01:55:46.376626Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.682464ms"} {"level":"info","ts":"2026-03-29T01:55:46.386147Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-03-29T01:55:46.386301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-03-29T01:55:46.386351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-03-29T01:55:46.386381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-29T01:55:46.386405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-03-29T01:55:46.386481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-03-29T01:55:46.391966Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-29T01:55:46.39693Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-29T01:55:46.398094Z","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-03-29T01:55:46.400409Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-29T01:55:46.4035Z","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-03-29T01:55:46.403734Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-29T01:55:46.40377Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-29T01:55:46.403923Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-29T01:55:46.404564Z","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-03-29T01:55:46.404753Z","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-03-29T01:55:46.404777Z","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-03-29T01:55:46.404844Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-03-29T01:55:46.40487Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-03-29T01:55:46.405543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-03-29T01:55:46.405685Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-03-29T01:55:47.387741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-03-29T01:55:47.387826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-29T01:55:47.387864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-03-29T01:55:47.387883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-03-29T01:55:47.387894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-03-29T01:55:47.38791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-03-29T01:55:47.387923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-03-29T01:55:47.406312Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-03-29T01:55:47.40641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-29T01:55:47.40644Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-29T01:55:47.406694Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:55:47.406846Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-29T01:55:47.40697Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-29T01:55:47.409953Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-29T01:55:47.410363Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-03-29T01:55:47.424336Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:55:47.424588Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:55:47.424686Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:56:54.577906Z","caller":"traceutil/trace.go:171","msg":"trace[2092747923] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"112.097843ms","start":"2026-03-29T01:56:54.465773Z","end":"2026-03-29T01:56:54.577871Z","steps":["trace[2092747923] 'process raft request' (duration: 111.99217ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:54.577839Z","caller":"traceutil/trace.go:171","msg":"trace[938978060] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"112.097392ms","start":"2026-03-29T01:56:54.465716Z","end":"2026-03-29T01:56:54.577813Z","steps":["trace[938978060] 'process raft request' (duration: 111.26733ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:54.588964Z","caller":"traceutil/trace.go:171","msg":"trace[1961673942] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"121.42202ms","start":"2026-03-29T01:56:54.467503Z","end":"2026-03-29T01:56:54.588925Z","steps":["trace[1961673942] 'process raft request' (duration: 121.182674ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:54.589224Z","caller":"traceutil/trace.go:171","msg":"trace[1360440203] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"117.076539ms","start":"2026-03-29T01:56:54.472119Z","end":"2026-03-29T01:56:54.589196Z","steps":["trace[1360440203] 'process raft request' (duration: 116.73908ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:54.764076Z","caller":"traceutil/trace.go:171","msg":"trace[566379087] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"141.925301ms","start":"2026-03-29T01:56:54.622124Z","end":"2026-03-29T01:56:54.764049Z","steps":["trace[566379087] 'process raft request' (duration: 107.877745ms)","trace[566379087] 'compare' (duration: 33.905043ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:56:54.764632Z","caller":"traceutil/trace.go:171","msg":"trace[312183917] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"132.205843ms","start":"2026-03-29T01:56:54.632402Z","end":"2026-03-29T01:56:54.764608Z","steps":["trace[312183917] 'process raft request' (duration: 132.031939ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:54.765361Z","caller":"traceutil/trace.go:171","msg":"trace[1181368353] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"105.300139ms","start":"2026-03-29T01:56:54.660038Z","end":"2026-03-29T01:56:54.765338Z","steps":["trace[1181368353] 'read index received' (duration: 69.987631ms)","trace[1181368353] 'applied index is now lower than readState.Index' (duration: 35.311468ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:56:54.765517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.462383ms","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-03-29T01:56:54.765636Z","caller":"traceutil/trace.go:171","msg":"trace[261337686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"105.623657ms","start":"2026-03-29T01:56:54.659992Z","end":"2026-03-29T01:56:54.765616Z","steps":["trace[261337686] 'agreement among raft nodes before linearized reading' (duration: 105.426633ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:00.813709Z","caller":"traceutil/trace.go:171","msg":"trace[1496854591] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"171.656939ms","start":"2026-03-29T01:57:00.642035Z","end":"2026-03-29T01:57:00.813692Z","steps":["trace[1496854591] 'process raft request' (duration: 170.944541ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:00.992713Z","caller":"traceutil/trace.go:171","msg":"trace[1882769551] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"119.771588ms","start":"2026-03-29T01:57:00.87291Z","end":"2026-03-29T01:57:00.992681Z","steps":["trace[1882769551] 'process raft request' (duration: 119.570223ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:01.865402Z","caller":"traceutil/trace.go:171","msg":"trace[1369740913] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"483.400539ms","start":"2026-03-29T01:57:01.381973Z","end":"2026-03-29T01:57:01.865373Z","steps":["trace[1369740913] 'process raft request' (duration: 483.265565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:01.866035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:01.381933Z","time spent":"483.543962ms","remote":"127.0.0.1:38070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:57:01.917519Z","caller":"traceutil/trace.go:171","msg":"trace[1811377153] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:731; }","duration":"496.317719ms","start":"2026-03-29T01:57:01.42118Z","end":"2026-03-29T01:57:01.917498Z","steps":["trace[1811377153] 'read index received' (duration: 444.12378ms)","trace[1811377153] 'applied index is now lower than readState.Index' (duration: 52.193229ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:01.917614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.416552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:01.917608Z","caller":"traceutil/trace.go:171","msg":"trace[538950592] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"507.823422ms","start":"2026-03-29T01:57:01.409755Z","end":"2026-03-29T01:57:01.917579Z","steps":["trace[538950592] 'process raft request' (duration: 499.753177ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:01.917637Z","caller":"traceutil/trace.go:171","msg":"trace[387426760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"496.502424ms","start":"2026-03-29T01:57:01.421127Z","end":"2026-03-29T01:57:01.917629Z","steps":["trace[387426760] 'agreement among raft nodes before linearized reading' (duration: 496.445222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:01.917774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:01.42111Z","time spent":"496.657408ms","remote":"127.0.0.1:55388","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-03-29T01:57:01.917827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:01.409715Z","time spent":"507.967926ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:17.361796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.078193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-29T01:57:17.361945Z","caller":"traceutil/trace.go:171","msg":"trace[907847899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:865; }","duration":"220.291308ms","start":"2026-03-29T01:57:17.141629Z","end":"2026-03-29T01:57:17.36192Z","steps":["trace[907847899] 'range keys from in-memory index tree' (duration: 219.815757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:17.361813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.040197ms","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-03-29T01:57:17.362173Z","caller":"traceutil/trace.go:171","msg":"trace[781655941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:865; }","duration":"211.439576ms","start":"2026-03-29T01:57:17.150684Z","end":"2026-03-29T01:57:17.362123Z","steps":["trace[781655941] 'range keys from in-memory index tree' (duration: 210.851992ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:59:11.107927Z","caller":"traceutil/trace.go:171","msg":"trace[1906300835] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"156.413443ms","start":"2026-03-29T01:59:10.951486Z","end":"2026-03-29T01:59:11.107899Z","steps":["trace[1906300835] 'process raft request' (duration: 156.199018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:59:11.282612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.189265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:59:11.283043Z","caller":"traceutil/trace.go:171","msg":"trace[1763835907] linearizableReadLoop","detail":"{readStateIndex:2314; appliedIndex:2312; }","duration":"151.565881ms","start":"2026-03-29T01:59:11.13145Z","end":"2026-03-29T01:59:11.283016Z","steps":["trace[1763835907] 'read index received' (duration: 23.69691ms)","trace[1763835907] 'applied index is now lower than readState.Index' (duration: 127.867201ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:59:11.28313Z","caller":"traceutil/trace.go:171","msg":"trace[1956403985] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"169.470377ms","start":"2026-03-29T01:59:11.113594Z","end":"2026-03-29T01:59:11.283065Z","steps":["trace[1956403985] 'process raft request' (duration: 41.397111ms)","trace[1956403985] 'compare' (duration: 127.034162ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:59:11.283462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.01048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-03-29T01:59:11.283428Z","caller":"traceutil/trace.go:171","msg":"trace[1194508813] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"169.376454ms","start":"2026-03-29T01:59:11.114019Z","end":"2026-03-29T01:59:11.283395Z","steps":["trace[1194508813] 'process raft request' (duration: 168.814531ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:59:11.283575Z","caller":"traceutil/trace.go:171","msg":"trace[1209593892] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2240; }","duration":"152.120713ms","start":"2026-03-29T01:59:11.131392Z","end":"2026-03-29T01:59:11.283513Z","steps":["trace[1209593892] 'agreement among raft nodes before linearized reading' (duration: 151.738464ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:00:23.492192Z","caller":"traceutil/trace.go:171","msg":"trace[1715722027] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"121.889457ms","start":"2026-03-29T02:00:23.370272Z","end":"2026-03-29T02:00:23.492162Z","steps":["trace[1715722027] 'process raft request' (duration: 121.666262ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:01:30.550605Z","caller":"traceutil/trace.go:171","msg":"trace[1811536078] linearizableReadLoop","detail":"{readStateIndex:3544; appliedIndex:3543; }","duration":"120.38349ms","start":"2026-03-29T02:01:30.430194Z","end":"2026-03-29T02:01:30.550578Z","steps":["trace[1811536078] 'read index received' (duration: 120.137404ms)","trace[1811536078] 'applied index is now lower than readState.Index' (duration: 244.496µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T02:01:30.550916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.710887ms","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-03-29T02:01:30.55096Z","caller":"traceutil/trace.go:171","msg":"trace[988507466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3409; }","duration":"120.786369ms","start":"2026-03-29T02:01:30.430163Z","end":"2026-03-29T02:01:30.550949Z","steps":["trace[988507466] 'agreement among raft nodes before linearized reading' (duration: 120.597854ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:01:30.663159Z","caller":"traceutil/trace.go:171","msg":"trace[1327259098] transaction","detail":"{read_only:false; response_revision:3411; number_of_response:1; }","duration":"107.903153ms","start":"2026-03-29T02:01:30.555206Z","end":"2026-03-29T02:01:30.663109Z","steps":["trace[1327259098] 'process raft request' (duration: 106.218484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:02:47.661241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.341749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:801"} {"level":"info","ts":"2026-03-29T02:02:47.661395Z","caller":"traceutil/trace.go:171","msg":"trace[850322016] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:4595; }","duration":"101.540033ms","start":"2026-03-29T02:02:47.559827Z","end":"2026-03-29T02:02:47.661367Z","steps":["trace[850322016] 'range keys from in-memory index tree' (duration: 101.109404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:02:52.459784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.00846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-03-29T02:02:52.459947Z","caller":"traceutil/trace.go:171","msg":"trace[1723031810] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4698; }","duration":"179.225884ms","start":"2026-03-29T02:02:52.280701Z","end":"2026-03-29T02:02:52.459927Z","steps":["trace[1723031810] 'range keys from in-memory index tree' (duration: 178.843356ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:02:52.650287Z","caller":"traceutil/trace.go:171","msg":"trace[1102884024] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"125.394547ms","start":"2026-03-29T02:02:52.524835Z","end":"2026-03-29T02:02:52.650229Z","steps":["trace[1102884024] 'process raft request' (duration: 43.225485ms)","trace[1102884024] 'compare' (duration: 81.874546ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T02:03:02.904565Z","caller":"traceutil/trace.go:171","msg":"trace[896024635] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"377.248126ms","start":"2026-03-29T02:03:02.527298Z","end":"2026-03-29T02:03:02.904546Z","steps":["trace[896024635] 'process raft request' (duration: 377.101203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:03:02.904692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T02:03:02.527267Z","time spent":"377.357049ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1434,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T02:03:02.904808Z","caller":"traceutil/trace.go:171","msg":"trace[1843400244] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5094; }","duration":"280.673726ms","start":"2026-03-29T02:03:02.624083Z","end":"2026-03-29T02:03:02.904757Z","steps":["trace[1843400244] 'read index received' (duration: 280.351138ms)","trace[1843400244] 'applied index is now lower than readState.Index' (duration: 320.818µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T02:03:02.90499Z","caller":"traceutil/trace.go:171","msg":"trace[1075044930] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"377.016312ms","start":"2026-03-29T02:03:02.527961Z","end":"2026-03-29T02:03:02.904977Z","steps":["trace[1075044930] 'process raft request' (duration: 376.5467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:03:02.905074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.007495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-03-29T02:03:02.905108Z","caller":"traceutil/trace.go:171","msg":"trace[1017059037] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4892; }","duration":"281.096476ms","start":"2026-03-29T02:03:02.624001Z","end":"2026-03-29T02:03:02.905098Z","steps":["trace[1017059037] 'agreement among raft nodes before linearized reading' (duration: 280.991584ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:03:02.905096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T02:03:02.527944Z","time spent":"377.081154ms","remote":"127.0.0.1:38394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3912,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T02:03:02.905243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.753661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-03-29T02:03:02.90534Z","caller":"traceutil/trace.go:171","msg":"trace[1645991229] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4892; }","duration":"116.900254ms","start":"2026-03-29T02:03:02.788413Z","end":"2026-03-29T02:03:02.905313Z","steps":["trace[1645991229] 'agreement among raft nodes before linearized reading' (duration: 116.633728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:03:02.905486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.109389ms","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-03-29T02:03:02.905584Z","caller":"traceutil/trace.go:171","msg":"trace[21217927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4892; }","duration":"203.372725ms","start":"2026-03-29T02:03:02.702191Z","end":"2026-03-29T02:03:02.905564Z","steps":["trace[21217927] 'agreement among raft nodes before linearized reading' (duration: 202.915875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:03:02.905834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.02456ms","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-03-29T02:03:02.90591Z","caller":"traceutil/trace.go:171","msg":"trace[1723911055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4892; }","duration":"204.161032ms","start":"2026-03-29T02:03:02.701734Z","end":"2026-03-29T02:03:02.905895Z","steps":["trace[1723911055] 'agreement among raft nodes before linearized reading' (duration: 203.283803ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:03:09.596945Z","caller":"traceutil/trace.go:171","msg":"trace[1600495115] linearizableReadLoop","detail":"{readStateIndex:5279; appliedIndex:5278; }","duration":"204.089ms","start":"2026-03-29T02:03:09.392829Z","end":"2026-03-29T02:03:09.596918Z","steps":["trace[1600495115] 'read index received' (duration: 158.820799ms)","trace[1600495115] 'applied index is now lower than readState.Index' (duration: 45.267261ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T02:03:09.597232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.547025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T02:03:09.597298Z","caller":"traceutil/trace.go:171","msg":"trace[1281133770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5069; }","duration":"173.659758ms","start":"2026-03-29T02:03:09.423623Z","end":"2026-03-29T02:03:09.597283Z","steps":["trace[1281133770] 'agreement among raft nodes before linearized reading' (duration: 173.485034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:03:09.59742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.593662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/monitoring/loki-headless-f4vwg\" ","response":"range_response_count:1 size:1457"} {"level":"info","ts":"2026-03-29T02:03:09.597468Z","caller":"traceutil/trace.go:171","msg":"trace[1251649894] range","detail":"{range_begin:/registry/endpointslices/monitoring/loki-headless-f4vwg; range_end:; response_count:1; response_revision:5069; }","duration":"204.684735ms","start":"2026-03-29T02:03:09.392771Z","end":"2026-03-29T02:03:09.597456Z","steps":["trace[1251649894] 'agreement among raft nodes before linearized reading' (duration: 204.303455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:03:09.597457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.017692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-03-29T02:03:09.597557Z","caller":"traceutil/trace.go:171","msg":"trace[1659613106] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5069; }","duration":"155.171167ms","start":"2026-03-29T02:03:09.442342Z","end":"2026-03-29T02:03:09.597514Z","steps":["trace[1659613106] 'agreement among raft nodes before linearized reading' (duration: 154.89161ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:03:09.597045Z","caller":"traceutil/trace.go:171","msg":"trace[650102972] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"220.601176ms","start":"2026-03-29T02:03:09.376405Z","end":"2026-03-29T02:03:09.597006Z","steps":["trace[650102972] 'process raft request' (duration: 175.192332ms)","trace[650102972] 'compare' (duration: 45.182769ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T02:05:47.428259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2954} {"level":"info","ts":"2026-03-29T02:05:47.490868Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2954,"took":"60.925706ms","hash":1517175948,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":35692544,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-03-29T02:05:47.490929Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1517175948,"revision":2954,"compact-revision":-1} {"level":"info","ts":"2026-03-29T02:08:57.319896Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002190.wal"} {"level":"info","ts":"2026-03-29T02:09:01.13411Z","caller":"traceutil/trace.go:171","msg":"trace[2018781644] transaction","detail":"{read_only:false; response_revision:8258; number_of_response:1; }","duration":"330.753316ms","start":"2026-03-29T02:09:00.803333Z","end":"2026-03-29T02:09:01.134087Z","steps":["trace[2018781644] 'process raft request' (duration: 330.616383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:09:01.134256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T02:09:00.803311Z","time spent":"330.869148ms","remote":"127.0.0.1:38144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T02:09:01.135604Z","caller":"traceutil/trace.go:171","msg":"trace[1474817515] linearizableReadLoop","detail":"{readStateIndex:8623; appliedIndex:8622; }","duration":"271.934876ms","start":"2026-03-29T02:09:00.863643Z","end":"2026-03-29T02:09:01.135578Z","steps":["trace[1474817515] 'read index received' (duration: 270.519934ms)","trace[1474817515] 'applied index is now lower than readState.Index' (duration: 1.413552ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T02:09:01.135842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.190382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-03-29T02:09:01.135898Z","caller":"traceutil/trace.go:171","msg":"trace[1487570658] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8259; }","duration":"272.282474ms","start":"2026-03-29T02:09:00.863602Z","end":"2026-03-29T02:09:01.135884Z","steps":["trace[1487570658] 'agreement among raft nodes before linearized reading' (duration: 272.12958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:09:01.135959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.244213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-03-29T02:09:01.136018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.786595ms","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-03-29T02:09:01.136045Z","caller":"traceutil/trace.go:171","msg":"trace[1918024518] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8259; }","duration":"272.393806ms","start":"2026-03-29T02:09:00.863632Z","end":"2026-03-29T02:09:01.136026Z","steps":["trace[1918024518] 'agreement among raft nodes before linearized reading' (duration: 272.155071ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:09:01.136079Z","caller":"traceutil/trace.go:171","msg":"trace[920511311] transaction","detail":"{read_only:false; response_revision:8259; number_of_response:1; }","duration":"331.548854ms","start":"2026-03-29T02:09:00.804518Z","end":"2026-03-29T02:09:01.136066Z","steps":["trace[920511311] 'process raft request' (duration: 330.94184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:09:01.136174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T02:09:00.804481Z","time spent":"331.653187ms","remote":"127.0.0.1:38144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T02:09:01.136083Z","caller":"traceutil/trace.go:171","msg":"trace[1920051993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8259; }","duration":"182.928558ms","start":"2026-03-29T02:09:00.953137Z","end":"2026-03-29T02:09:01.136066Z","steps":["trace[1920051993] 'agreement among raft nodes before linearized reading' (duration: 182.740354ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:09:12.860038Z","caller":"traceutil/trace.go:171","msg":"trace[1457734502] transaction","detail":"{read_only:false; response_revision:8338; number_of_response:1; }","duration":"104.002606ms","start":"2026-03-29T02:09:12.756012Z","end":"2026-03-29T02:09:12.860015Z","steps":["trace[1457734502] 'process raft request' (duration: 103.814661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:09:13.421207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.109585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-03-29T02:09:13.421252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.946699ms","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-03-29T02:09:13.42131Z","caller":"traceutil/trace.go:171","msg":"trace[397495175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8339; }","duration":"234.066362ms","start":"2026-03-29T02:09:13.18723Z","end":"2026-03-29T02:09:13.421297Z","steps":["trace[397495175] 'range keys from in-memory index tree' (duration: 233.754074ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:09:13.421303Z","caller":"traceutil/trace.go:171","msg":"trace[1155146625] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8339; }","duration":"129.249088ms","start":"2026-03-29T02:09:13.29203Z","end":"2026-03-29T02:09:13.421279Z","steps":["trace[1155146625] 'range keys from in-memory index tree' (duration: 128.91587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:09:13.421298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.49839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-29T02:09:13.42126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.418283ms","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-03-29T02:09:13.421357Z","caller":"traceutil/trace.go:171","msg":"trace[2114019018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8339; }","duration":"208.558302ms","start":"2026-03-29T02:09:13.212785Z","end":"2026-03-29T02:09:13.421344Z","steps":["trace[2114019018] 'range keys from in-memory index tree' (duration: 208.380908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:09:13.421353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.967591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-03-29T02:09:13.421398Z","caller":"traceutil/trace.go:171","msg":"trace[1794700949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8339; }","duration":"213.686329ms","start":"2026-03-29T02:09:13.20768Z","end":"2026-03-29T02:09:13.421367Z","steps":["trace[1794700949] 'range keys from in-memory index tree' (duration: 213.140667ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:09:13.421453Z","caller":"traceutil/trace.go:171","msg":"trace[412878777] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8339; }","duration":"119.103394ms","start":"2026-03-29T02:09:13.302323Z","end":"2026-03-29T02:09:13.421427Z","steps":["trace[412878777] 'range keys from in-memory index tree' (duration: 118.792827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:09:13.421832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.489729ms","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":"warn","ts":"2026-03-29T02:09:13.421821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.481847ms","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-03-29T02:09:13.421851Z","caller":"traceutil/trace.go:171","msg":"trace[882177158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8339; }","duration":"214.55977ms","start":"2026-03-29T02:09:13.207286Z","end":"2026-03-29T02:09:13.421846Z","steps":["trace[882177158] 'range keys from in-memory index tree' (duration: 214.186161ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:09:13.421861Z","caller":"traceutil/trace.go:171","msg":"trace[1872863420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8339; }","duration":"230.557649ms","start":"2026-03-29T02:09:13.191293Z","end":"2026-03-29T02:09:13.421851Z","steps":["trace[1872863420] 'range keys from in-memory index tree' (duration: 230.160851ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:09:13.96312Z","caller":"traceutil/trace.go:171","msg":"trace[469829852] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"301.044629ms","start":"2026-03-29T02:09:13.662047Z","end":"2026-03-29T02:09:13.963091Z","steps":["trace[469829852] 'process raft request' (duration: 255.099328ms)","trace[469829852] 'compare' (duration: 45.791678ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T02:09:13.963271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T02:09:13.662018Z","time spent":"301.181422ms","remote":"127.0.0.1:38144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T02:09:42.288823Z","caller":"traceutil/trace.go:171","msg":"trace[1684877647] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"229.024045ms","start":"2026-03-29T02:09:42.059768Z","end":"2026-03-29T02:09:42.288792Z","steps":["trace[1684877647] 'process raft request' (duration: 147.046539ms)","trace[1684877647] 'compare' (duration: 81.856563ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T02:09:56.387784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.70589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T02:09:56.38807Z","caller":"traceutil/trace.go:171","msg":"trace[1377067133] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"301.308906ms","start":"2026-03-29T02:09:56.086747Z","end":"2026-03-29T02:09:56.388056Z","steps":["trace[1377067133] 'process raft request' (duration: 13.236506ms)","trace[1377067133] 'compare' (duration: 287.555027ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T02:09:56.388221Z","caller":"traceutil/trace.go:171","msg":"trace[1423803056] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"299.011633ms","start":"2026-03-29T02:09:56.089192Z","end":"2026-03-29T02:09:56.388204Z","steps":["trace[1423803056] 'process raft request' (duration: 298.672725ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:09:56.388239Z","caller":"traceutil/trace.go:171","msg":"trace[1432226433] linearizableReadLoop","detail":"{readStateIndex:9120; appliedIndex:9118; }","duration":"116.219417ms","start":"2026-03-29T02:09:56.271993Z","end":"2026-03-29T02:09:56.388212Z","steps":["trace[1432226433] 'read index received' (duration: 47.721153ms)","trace[1432226433] 'applied index is now lower than readState.Index' (duration: 68.496164ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T02:09:56.388371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.35707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T02:09:56.388407Z","caller":"traceutil/trace.go:171","msg":"trace[2047524746] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8729; }","duration":"116.450862ms","start":"2026-03-29T02:09:56.271947Z","end":"2026-03-29T02:09:56.388398Z","steps":["trace[2047524746] 'agreement among raft nodes before linearized reading' (duration: 116.35382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:09:56.388425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T02:09:56.086731Z","time spent":"301.365217ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T02:10:47.434849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6439} {"level":"info","ts":"2026-03-29T02:10:47.536187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6439,"took":"99.059608ms","hash":462225330,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":26750976,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-29T02:10:47.536249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":462225330,"revision":6439,"compact-revision":2954} {"level":"info","ts":"2026-03-29T02:10:58.199721Z","caller":"traceutil/trace.go:171","msg":"trace[1833564601] transaction","detail":"{read_only:false; response_revision:9418; number_of_response:1; }","duration":"101.790541ms","start":"2026-03-29T02:10:58.097897Z","end":"2026-03-29T02:10:58.199687Z","steps":["trace[1833564601] 'process raft request' (duration: 101.674128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:10:58.19976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.544332ms","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-03-29T02:10:58.199844Z","caller":"traceutil/trace.go:171","msg":"trace[1292316145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9418; }","duration":"103.648655ms","start":"2026-03-29T02:10:58.096179Z","end":"2026-03-29T02:10:58.199828Z","steps":["trace[1292316145] 'agreement among raft nodes before linearized reading' (duration: 103.478871ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:10:58.345374Z","caller":"traceutil/trace.go:171","msg":"trace[830819275] transaction","detail":"{read_only:false; response_revision:9420; number_of_response:1; }","duration":"141.943123ms","start":"2026-03-29T02:10:58.203406Z","end":"2026-03-29T02:10:58.345349Z","steps":["trace[830819275] 'process raft request' (duration: 80.917601ms)","trace[830819275] 'compare' (duration: 60.537931ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T02:10:58.345535Z","caller":"traceutil/trace.go:171","msg":"trace[1098807566] linearizableReadLoop","detail":"{readStateIndex:9843; appliedIndex:9841; }","duration":"141.34619ms","start":"2026-03-29T02:10:58.204156Z","end":"2026-03-29T02:10:58.345502Z","steps":["trace[1098807566] 'read index received' (duration: 45.058369ms)","trace[1098807566] 'applied index is now lower than readState.Index' (duration: 96.286021ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T02:10:58.345622Z","caller":"traceutil/trace.go:171","msg":"trace[1709157682] transaction","detail":"{read_only:false; response_revision:9421; number_of_response:1; }","duration":"141.129895ms","start":"2026-03-29T02:10:58.204447Z","end":"2026-03-29T02:10:58.345577Z","steps":["trace[1709157682] 'process raft request' (duration: 140.819007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:10:58.345778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.024885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T02:10:58.345849Z","caller":"traceutil/trace.go:171","msg":"trace[1270066119] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:9421; }","duration":"133.150578ms","start":"2026-03-29T02:10:58.212679Z","end":"2026-03-29T02:10:58.345829Z","steps":["trace[1270066119] 'agreement among raft nodes before linearized reading' (duration: 133.007144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T02:10:58.345978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.807921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:28 size:241235"} {"level":"info","ts":"2026-03-29T02:10:58.346025Z","caller":"traceutil/trace.go:171","msg":"trace[1542953272] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:28; response_revision:9421; }","duration":"141.885984ms","start":"2026-03-29T02:10:58.20413Z","end":"2026-03-29T02:10:58.346016Z","steps":["trace[1542953272] 'agreement among raft nodes before linearized reading' (duration: 141.463803ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T02:11:13.274181Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e10bb51ed7578852","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-29T02:11:13.279012Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-29T02:11:13.279148Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-29T02:15:47.440549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9343} {"level":"info","ts":"2026-03-29T02:15:47.527169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9343,"took":"84.378597ms","hash":1961202948,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":25583616,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-29T02:15:47.527252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1961202948,"revision":9343,"compact-revision":6439}