{"level":"warn","ts":"2026-03-03T18:14:41.064186Z","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-03T18:14:41.064291Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-03T18:14:41.064369Z","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-03T18:14:41.064386Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]}
{"level":"info","ts":"2026-03-03T18:14:41.064405Z","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-03T18:14:41.065257Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]}
{"level":"info","ts":"2026-03-03T18:14:41.065345Z","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.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209: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-03T18:14:41.070498Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.937248ms"}
{"level":"info","ts":"2026-03-03T18:14:41.075818Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"}
{"level":"info","ts":"2026-03-03T18:14:41.075919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-03T18:14:41.075956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"}
{"level":"info","ts":"2026-03-03T18:14:41.07598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-03T18:14:41.075992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"}
{"level":"info","ts":"2026-03-03T18:14:41.076176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"}
{"level":"warn","ts":"2026-03-03T18:14:41.082127Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-03T18:14:41.085416Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-03T18:14:41.087327Z","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-03T18:14:41.090763Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-03T18:14:41.093443Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-03T18:14:41.093588Z","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-03T18:14:41.093702Z","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-03T18:14:41.093721Z","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-03T18:14:41.094866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"}
{"level":"info","ts":"2026-03-03T18:14:41.095045Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]}
{"level":"info","ts":"2026-03-03T18:14:41.097484Z","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-03T18:14:41.097724Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"}
{"level":"info","ts":"2026-03-03T18:14:41.097863Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"}
{"level":"info","ts":"2026-03-03T18:14:41.099153Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-03T18:14:41.099212Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-03T18:14:41.376751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-03T18:14:41.376827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-03T18:14:41.376924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"}
{"level":"info","ts":"2026-03-03T18:14:41.376979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"}
{"level":"info","ts":"2026-03-03T18:14:41.377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"}
{"level":"info","ts":"2026-03-03T18:14:41.377064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"}
{"level":"info","ts":"2026-03-03T18:14:41.377123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"}
{"level":"info","ts":"2026-03-03T18:14:41.378223Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-03T18:14:41.378391Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-03T18:14:41.378279Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-03T18:14:41.378566Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-03T18:14:41.378634Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-03T18:14:41.378691Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-03T18:14:41.379496Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-03T18:14:41.379615Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-03T18:14:41.379652Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-03T18:14:41.381558Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-03T18:14:41.383502Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"}
{"level":"warn","ts":"2026-03-03T18:15:17.526837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.60681ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:15:17.527006Z","caller":"traceutil/trace.go:171","msg":"trace[1080564544] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:510; }","duration":"421.828806ms","start":"2026-03-03T18:15:17.105157Z","end":"2026-03-03T18:15:17.526986Z","steps":["trace[1080564544] 'range keys from in-memory index tree'  (duration: 421.58976ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:15:17.527534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.39837ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13228088831330924655 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:472 > success:<request_put:<key:\"/registry/minions/instance\" value_size:4715 >> failure:<request_range:<key:\"/registry/minions/instance\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-03T18:15:17.527653Z","caller":"traceutil/trace.go:171","msg":"trace[1319880005] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"438.960327ms","start":"2026-03-03T18:15:17.088676Z","end":"2026-03-03T18:15:17.527637Z","steps":["trace[1319880005] 'read index received'  (duration: 106.820761ms)","trace[1319880005] 'applied index is now lower than readState.Index'  (duration: 332.138226ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:15:17.5277Z","caller":"traceutil/trace.go:171","msg":"trace[1937710619] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"468.67292ms","start":"2026-03-03T18:15:17.058991Z","end":"2026-03-03T18:15:17.527664Z","steps":["trace[1937710619] 'process raft request'  (duration: 136.573415ms)","trace[1937710619] 'compare'  (duration: 331.194754ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-03T18:15:17.527843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.164441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-03T18:15:17.527881Z","caller":"traceutil/trace.go:171","msg":"trace[197705854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"439.251563ms","start":"2026-03-03T18:15:17.08862Z","end":"2026-03-03T18:15:17.527871Z","steps":["trace[197705854] 'agreement among raft nodes before linearized reading'  (duration: 439.11473ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:15:17.527926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:15:17.088586Z","time spent":"439.331765ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-03T18:15:17.527912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.747874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-03T18:15:17.52792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:15:17.058974Z","time spent":"468.855224ms","remote":"127.0.0.1:53008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4749,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:472 > success:<request_put:<key:\"/registry/minions/instance\" value_size:4715 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"warn","ts":"2026-03-03T18:15:17.527955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.433743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"}
{"level":"warn","ts":"2026-03-03T18:15:17.527983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.198597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"}
{"level":"warn","ts":"2026-03-03T18:15:17.528054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.872216ms","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-03T18:15:17.528085Z","caller":"traceutil/trace.go:171","msg":"trace[1216266908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"231.939798ms","start":"2026-03-03T18:15:17.296117Z","end":"2026-03-03T18:15:17.528057Z","steps":["trace[1216266908] 'agreement among raft nodes before linearized reading'  (duration: 231.721223ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:17.528095Z","caller":"traceutil/trace.go:171","msg":"trace[968437295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"320.610296ms","start":"2026-03-03T18:15:17.207469Z","end":"2026-03-03T18:15:17.52808Z","steps":["trace[968437295] 'agreement among raft nodes before linearized reading'  (duration: 320.31091ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:17.528112Z","caller":"traceutil/trace.go:171","msg":"trace[1890682707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"314.3319ms","start":"2026-03-03T18:15:17.213759Z","end":"2026-03-03T18:15:17.528091Z","steps":["trace[1890682707] 'agreement among raft nodes before linearized reading'  (duration: 314.083324ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:17.528135Z","caller":"traceutil/trace.go:171","msg":"trace[889268476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"116.004089ms","start":"2026-03-03T18:15:17.412116Z","end":"2026-03-03T18:15:17.52812Z","steps":["trace[889268476] 'agreement among raft nodes before linearized reading'  (duration: 115.748054ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:15:17.52815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:15:17.207454Z","time spent":"320.682728ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-03-03T18:15:17.528161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:15:17.213755Z","time spent":"314.394611ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-03-03T18:15:17.724466Z","caller":"traceutil/trace.go:171","msg":"trace[82177462] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"191.182706ms","start":"2026-03-03T18:15:17.533231Z","end":"2026-03-03T18:15:17.724414Z","steps":["trace[82177462] 'process raft request'  (duration: 136.275487ms)","trace[82177462] 'compare'  (duration: 54.722685ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:15:17.724734Z","caller":"traceutil/trace.go:171","msg":"trace[1547669578] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:529; }","duration":"150.798993ms","start":"2026-03-03T18:15:17.573906Z","end":"2026-03-03T18:15:17.724705Z","steps":["trace[1547669578] 'read index received'  (duration: 95.69058ms)","trace[1547669578] 'applied index is now lower than readState.Index'  (duration: 55.107303ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:15:17.724801Z","caller":"traceutil/trace.go:171","msg":"trace[1782759445] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"190.315358ms","start":"2026-03-03T18:15:17.534474Z","end":"2026-03-03T18:15:17.724789Z","steps":["trace[1782759445] 'process raft request'  (duration: 190.122904ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:15:17.724859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.928126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:15:17.724873Z","caller":"traceutil/trace.go:171","msg":"trace[1397184858] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"189.721015ms","start":"2026-03-03T18:15:17.535124Z","end":"2026-03-03T18:15:17.724845Z","steps":["trace[1397184858] 'process raft request'  (duration: 189.527731ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:17.724899Z","caller":"traceutil/trace.go:171","msg":"trace[592151149] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:515; }","duration":"150.986487ms","start":"2026-03-03T18:15:17.5739Z","end":"2026-03-03T18:15:17.724887Z","steps":["trace[592151149] 'agreement among raft nodes before linearized reading'  (duration: 150.905145ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:17.724887Z","caller":"traceutil/trace.go:171","msg":"trace[1487533673] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"190.536212ms","start":"2026-03-03T18:15:17.534319Z","end":"2026-03-03T18:15:17.724856Z","steps":["trace[1487533673] 'process raft request'  (duration: 190.061262ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:18.675412Z","caller":"traceutil/trace.go:171","msg":"trace[783094338] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"122.55749ms","start":"2026-03-03T18:15:18.55283Z","end":"2026-03-03T18:15:18.675387Z","steps":["trace[783094338] 'process raft request'  (duration: 122.53002ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:18.675518Z","caller":"traceutil/trace.go:171","msg":"trace[2039042240] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"123.01708ms","start":"2026-03-03T18:15:18.552441Z","end":"2026-03-03T18:15:18.675458Z","steps":["trace[2039042240] 'process raft request'  (duration: 110.679443ms)","trace[2039042240] 'compare'  (duration: 12.040001ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:15:18.675546Z","caller":"traceutil/trace.go:171","msg":"trace[1919016835] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"122.827107ms","start":"2026-03-03T18:15:18.552683Z","end":"2026-03-03T18:15:18.67551Z","steps":["trace[1919016835] 'process raft request'  (duration: 122.648822ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:15:38.008286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.228338ms","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-03T18:15:38.008619Z","caller":"traceutil/trace.go:171","msg":"trace[1966448184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:889; }","duration":"191.650477ms","start":"2026-03-03T18:15:37.816933Z","end":"2026-03-03T18:15:38.008583Z","steps":["trace[1966448184] 'range keys from in-memory index tree'  (duration: 190.842569ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:15:38.008695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.380543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"warn","ts":"2026-03-03T18:15:38.00831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.394703ms","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-03T18:15:38.00883Z","caller":"traceutil/trace.go:171","msg":"trace[812817367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"180.569887ms","start":"2026-03-03T18:15:37.828231Z","end":"2026-03-03T18:15:38.0088Z","steps":["trace[812817367] 'range keys from in-memory index tree'  (duration: 180.092917ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:38.008931Z","caller":"traceutil/trace.go:171","msg":"trace[2022396215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"187.046466ms","start":"2026-03-03T18:15:37.821834Z","end":"2026-03-03T18:15:38.00888Z","steps":["trace[2022396215] 'range keys from in-memory index tree'  (duration: 186.175418ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:15:38.008435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.454725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-03-03T18:15:38.009079Z","caller":"traceutil/trace.go:171","msg":"trace[1219042201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:889; }","duration":"181.079868ms","start":"2026-03-03T18:15:37.827952Z","end":"2026-03-03T18:15:38.009032Z","steps":["trace[1219042201] 'range keys from in-memory index tree'  (duration: 180.323972ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:38.140632Z","caller":"traceutil/trace.go:171","msg":"trace[816327870] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"127.259003ms","start":"2026-03-03T18:15:38.013343Z","end":"2026-03-03T18:15:38.140602Z","steps":["trace[816327870] 'process raft request'  (duration: 127.074209ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:38.1407Z","caller":"traceutil/trace.go:171","msg":"trace[223920126] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"127.026297ms","start":"2026-03-03T18:15:38.01364Z","end":"2026-03-03T18:15:38.140666Z","steps":["trace[223920126] 'process raft request'  (duration: 126.920285ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:15:38.140636Z","caller":"traceutil/trace.go:171","msg":"trace[974118503] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"127.301524ms","start":"2026-03-03T18:15:38.013268Z","end":"2026-03-03T18:15:38.140569Z","steps":["trace[974118503] 'process raft request'  (duration: 114.285212ms)","trace[974118503] 'compare'  (duration: 12.727586ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:16:00.253026Z","caller":"traceutil/trace.go:171","msg":"trace[1744907348] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"106.503204ms","start":"2026-03-03T18:16:00.146491Z","end":"2026-03-03T18:16:00.252994Z","steps":["trace[1744907348] 'process raft request'  (duration: 105.663366ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:16:24.972354Z","caller":"traceutil/trace.go:171","msg":"trace[1770435315] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"136.68864ms","start":"2026-03-03T18:16:24.835634Z","end":"2026-03-03T18:16:24.972323Z","steps":["trace[1770435315] 'process raft request'  (duration: 136.466426ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:17:15.893499Z","caller":"traceutil/trace.go:171","msg":"trace[556204950] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"231.028059ms","start":"2026-03-03T18:17:15.662444Z","end":"2026-03-03T18:17:15.893473Z","steps":["trace[556204950] 'process raft request'  (duration: 230.894556ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:17:15.933767Z","caller":"traceutil/trace.go:171","msg":"trace[57530959] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1919; }","duration":"161.448731ms","start":"2026-03-03T18:17:15.772302Z","end":"2026-03-03T18:17:15.93375Z","steps":["trace[57530959] 'read index received'  (duration: 121.03266ms)","trace[57530959] 'applied index is now lower than readState.Index'  (duration: 40.415021ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-03T18:17:15.933871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.552724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:17:15.933911Z","caller":"traceutil/trace.go:171","msg":"trace[1611153732] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1851; }","duration":"161.664356ms","start":"2026-03-03T18:17:15.772238Z","end":"2026-03-03T18:17:15.933902Z","steps":["trace[1611153732] 'agreement among raft nodes before linearized reading'  (duration: 161.571724ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:17:15.933647Z","caller":"traceutil/trace.go:171","msg":"trace[1348013092] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"248.766305ms","start":"2026-03-03T18:17:15.684826Z","end":"2026-03-03T18:17:15.933593Z","steps":["trace[1348013092] 'process raft request'  (duration: 248.49324ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:18:41.271903Z","caller":"traceutil/trace.go:171","msg":"trace[1799884515] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"127.212568ms","start":"2026-03-03T18:18:41.144665Z","end":"2026-03-03T18:18:41.271878Z","steps":["trace[1799884515] 'process raft request'  (duration: 127.051534ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:18:41.589657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.167298ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13228088831330928786 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:2455 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-03T18:18:41.589803Z","caller":"traceutil/trace.go:171","msg":"trace[1308846875] linearizableReadLoop","detail":"{readStateIndex:2558; appliedIndex:2556; }","duration":"440.240967ms","start":"2026-03-03T18:18:41.149539Z","end":"2026-03-03T18:18:41.58978Z","steps":["trace[1308846875] 'read index received'  (duration: 122.180976ms)","trace[1308846875] 'applied index is now lower than readState.Index'  (duration: 318.058271ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:18:41.589926Z","caller":"traceutil/trace.go:171","msg":"trace[474911609] transaction","detail":"{read_only:false; response_revision:2463; number_of_response:1; }","duration":"442.355344ms","start":"2026-03-03T18:18:41.147546Z","end":"2026-03-03T18:18:41.589901Z","steps":["trace[474911609] 'process raft request'  (duration: 263.840147ms)","trace[474911609] 'compare'  (duration: 177.867252ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-03T18:18:41.59004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.487893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"}
{"level":"warn","ts":"2026-03-03T18:18:41.590054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:18:41.147512Z","time spent":"442.480456ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:2455 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-03-03T18:18:41.590063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.965893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-03T18:18:41.590095Z","caller":"traceutil/trace.go:171","msg":"trace[1037029078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2463; }","duration":"440.571694ms","start":"2026-03-03T18:18:41.149511Z","end":"2026-03-03T18:18:41.590082Z","steps":["trace[1037029078] 'agreement among raft nodes before linearized reading'  (duration: 440.38936ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:18:41.590118Z","caller":"traceutil/trace.go:171","msg":"trace[1575282377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2463; }","duration":"437.035765ms","start":"2026-03-03T18:18:41.153067Z","end":"2026-03-03T18:18:41.590103Z","steps":["trace[1575282377] 'agreement among raft nodes before linearized reading'  (duration: 436.910402ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:18:41.590144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:18:41.149499Z","time spent":"440.633625ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-03-03T18:18:41.590156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:18:41.15306Z","time spent":"437.085826ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-03-03T18:21:12.196961Z","caller":"traceutil/trace.go:171","msg":"trace[235948742] linearizableReadLoop","detail":"{readStateIndex:3517; appliedIndex:3516; }","duration":"108.614001ms","start":"2026-03-03T18:21:12.088322Z","end":"2026-03-03T18:21:12.196936Z","steps":["trace[235948742] 'read index received'  (duration: 108.360066ms)","trace[235948742] 'applied index is now lower than readState.Index'  (duration: 253.145µs)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:21:12.197008Z","caller":"traceutil/trace.go:171","msg":"trace[265084099] transaction","detail":"{read_only:false; response_revision:3384; number_of_response:1; }","duration":"108.729964ms","start":"2026-03-03T18:21:12.088257Z","end":"2026-03-03T18:21:12.196987Z","steps":["trace[265084099] 'process raft request'  (duration: 108.446618ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:21:12.19726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.917598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:63177"}
{"level":"info","ts":"2026-03-03T18:21:12.197299Z","caller":"traceutil/trace.go:171","msg":"trace[1437387195] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:3384; }","duration":"108.991631ms","start":"2026-03-03T18:21:12.088297Z","end":"2026-03-03T18:21:12.197289Z","steps":["trace[1437387195] 'agreement among raft nodes before linearized reading'  (duration: 108.735884ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:22:28.53883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.570587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-tls\" ","response":"range_response_count:1 size:3009"}
{"level":"warn","ts":"2026-03-03T18:22:28.538839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.325384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1261"}
{"level":"info","ts":"2026-03-03T18:22:28.538936Z","caller":"traceutil/trace.go:171","msg":"trace[1467763167] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-tls; range_end:; response_count:1; response_revision:4181; }","duration":"171.684159ms","start":"2026-03-03T18:22:28.367207Z","end":"2026-03-03T18:22:28.538891Z","steps":["trace[1467763167] 'range keys from in-memory index tree'  (duration: 171.414923ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:22:28.538957Z","caller":"traceutil/trace.go:171","msg":"trace[1447460262] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4181; }","duration":"156.483398ms","start":"2026-03-03T18:22:28.382455Z","end":"2026-03-03T18:22:28.538938Z","steps":["trace[1447460262] 'range keys from in-memory index tree'  (duration: 156.12053ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:22:28.547016Z","caller":"traceutil/trace.go:171","msg":"trace[2035777245] transaction","detail":"{read_only:false; response_revision:4182; number_of_response:1; }","duration":"124.629221ms","start":"2026-03-03T18:22:28.422361Z","end":"2026-03-03T18:22:28.54699Z","steps":["trace[2035777245] 'process raft request'  (duration: 124.413727ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:22:39.168696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.770173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:718804"}
{"level":"info","ts":"2026-03-03T18:22:39.168797Z","caller":"traceutil/trace.go:171","msg":"trace[1163376325] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4518; }","duration":"139.922746ms","start":"2026-03-03T18:22:39.028853Z","end":"2026-03-03T18:22:39.168776Z","steps":["trace[1163376325] 'range keys from in-memory index tree'  (duration: 139.24857ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:22:43.389773Z","caller":"traceutil/trace.go:171","msg":"trace[56086585] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"137.789709ms","start":"2026-03-03T18:22:43.251959Z","end":"2026-03-03T18:22:43.389749Z","steps":["trace[56086585] 'process raft request'  (duration: 137.668257ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:22:43.389764Z","caller":"traceutil/trace.go:171","msg":"trace[1770687274] transaction","detail":"{read_only:false; response_revision:4605; number_of_response:1; }","duration":"139.2385ms","start":"2026-03-03T18:22:43.250474Z","end":"2026-03-03T18:22:43.389712Z","steps":["trace[1770687274] 'process raft request'  (duration: 139.035236ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:24:01.589955Z","caller":"traceutil/trace.go:171","msg":"trace[1360058648] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"109.318062ms","start":"2026-03-03T18:24:01.4806Z","end":"2026-03-03T18:24:01.589918Z","steps":["trace[1360058648] 'process raft request'  (duration: 108.126626ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:24:01.590014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.540761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-03T18:24:01.590087Z","caller":"traceutil/trace.go:171","msg":"trace[1599919397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5445; }","duration":"117.650624ms","start":"2026-03-03T18:24:01.472417Z","end":"2026-03-03T18:24:01.590068Z","steps":["trace[1599919397] 'agreement among raft nodes before linearized reading'  (duration: 117.420039ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:24:41.669764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2760}
{"level":"info","ts":"2026-03-03T18:24:41.740768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2760,"took":"69.759046ms","hash":835284233,"current-db-size-bytes":40865792,"current-db-size":"41 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-03-03T18:24:41.740833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":835284233,"revision":2760,"compact-revision":-1}
{"level":"info","ts":"2026-03-03T18:29:31.153611Z","caller":"traceutil/trace.go:171","msg":"trace[1780008642] transaction","detail":"{read_only:false; response_revision:7681; number_of_response:1; }","duration":"194.709699ms","start":"2026-03-03T18:29:30.958872Z","end":"2026-03-03T18:29:31.153582Z","steps":["trace[1780008642] 'process raft request'  (duration: 151.712833ms)","trace[1780008642] 'compare'  (duration: 42.888623ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:29:31.491668Z","caller":"traceutil/trace.go:171","msg":"trace[1691895361] linearizableReadLoop","detail":"{readStateIndex:7967; appliedIndex:7964; }","duration":"438.449853ms","start":"2026-03-03T18:29:31.053196Z","end":"2026-03-03T18:29:31.491645Z","steps":["trace[1691895361] 'read index received'  (duration: 57.477056ms)","trace[1691895361] 'applied index is now lower than readState.Index'  (duration: 380.971787ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:29:31.491762Z","caller":"traceutil/trace.go:171","msg":"trace[648440690] transaction","detail":"{read_only:false; response_revision:7683; number_of_response:1; }","duration":"447.525146ms","start":"2026-03-03T18:29:31.044208Z","end":"2026-03-03T18:29:31.491733Z","steps":["trace[648440690] 'process raft request'  (duration: 447.389293ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:29:31.491725Z","caller":"traceutil/trace.go:171","msg":"trace[487345280] transaction","detail":"{read_only:false; response_revision:7682; number_of_response:1; }","duration":"450.104421ms","start":"2026-03-03T18:29:31.041575Z","end":"2026-03-03T18:29:31.49168Z","steps":["trace[487345280] 'process raft request'  (duration: 449.824785ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:29:31.491905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:29:31.044179Z","time spent":"447.646519ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:7674 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-03T18:29:31.492002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.78297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"}
{"level":"warn","ts":"2026-03-03T18:29:31.492076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:29:31.041543Z","time spent":"450.377387ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:7673 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-03-03T18:29:31.492127Z","caller":"traceutil/trace.go:171","msg":"trace[1728577152] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7683; }","duration":"438.957444ms","start":"2026-03-03T18:29:31.053133Z","end":"2026-03-03T18:29:31.492091Z","steps":["trace[1728577152] 'agreement among raft nodes before linearized reading'  (duration: 438.631937ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:29:31.492184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:29:31.053108Z","time spent":"439.067166ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"warn","ts":"2026-03-03T18:29:31.503251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.110058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-03-03T18:29:31.503323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.312336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-03T18:29:31.503347Z","caller":"traceutil/trace.go:171","msg":"trace[737960560] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:7684; }","duration":"142.229551ms","start":"2026-03-03T18:29:31.361102Z","end":"2026-03-03T18:29:31.503332Z","steps":["trace[737960560] 'agreement among raft nodes before linearized reading'  (duration: 142.061708ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:29:31.503524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.485073ms","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-03T18:29:31.503548Z","caller":"traceutil/trace.go:171","msg":"trace[1818258853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7684; }","duration":"400.527155ms","start":"2026-03-03T18:29:31.103015Z","end":"2026-03-03T18:29:31.503542Z","steps":["trace[1818258853] 'agreement among raft nodes before linearized reading'  (duration: 400.473974ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:29:31.503574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:29:31.103005Z","time spent":"400.564345ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-03-03T18:29:31.503359Z","caller":"traceutil/trace.go:171","msg":"trace[1638525075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7684; }","duration":"175.372238ms","start":"2026-03-03T18:29:31.327976Z","end":"2026-03-03T18:29:31.503349Z","steps":["trace[1638525075] 'agreement among raft nodes before linearized reading'  (duration: 175.194984ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:29:31.503417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.629149ms","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-03T18:29:31.50367Z","caller":"traceutil/trace.go:171","msg":"trace[1602953175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7684; }","duration":"180.905005ms","start":"2026-03-03T18:29:31.32276Z","end":"2026-03-03T18:29:31.503665Z","steps":["trace[1602953175] 'agreement among raft nodes before linearized reading'  (duration: 180.615949ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:29:31.503472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.284963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6bgxc\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-03-03T18:29:31.503725Z","caller":"traceutil/trace.go:171","msg":"trace[516853764] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6bgxc; range_end:; response_count:1; response_revision:7684; }","duration":"396.55518ms","start":"2026-03-03T18:29:31.107166Z","end":"2026-03-03T18:29:31.503721Z","steps":["trace[516853764] 'agreement among raft nodes before linearized reading'  (duration: 396.263054ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:29:31.503742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:29:31.107157Z","time spent":"396.580641ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-6bgxc\" "}
{"level":"info","ts":"2026-03-03T18:29:32.425072Z","caller":"traceutil/trace.go:171","msg":"trace[1411840970] linearizableReadLoop","detail":"{readStateIndex:7973; appliedIndex:7972; }","duration":"167.307255ms","start":"2026-03-03T18:29:32.257738Z","end":"2026-03-03T18:29:32.425045Z","steps":["trace[1411840970] 'read index received'  (duration: 167.086171ms)","trace[1411840970] 'applied index is now lower than readState.Index'  (duration: 216.364µs)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:29:32.42521Z","caller":"traceutil/trace.go:171","msg":"trace[1396187810] transaction","detail":"{read_only:false; response_revision:7689; number_of_response:1; }","duration":"307.110765ms","start":"2026-03-03T18:29:32.118088Z","end":"2026-03-03T18:29:32.425199Z","steps":["trace[1396187810] 'process raft request'  (duration: 306.766287ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:29:32.425349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:29:32.118069Z","time spent":"307.168165ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/cert-manager/cert-manager-controller\" mod_revision:7619 > success:<request_put:<key:\"/registry/leases/cert-manager/cert-manager-controller\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/cert-manager/cert-manager-controller\" > >"}
{"level":"warn","ts":"2026-03-03T18:29:32.425432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.068359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-03T18:29:32.425468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.690313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:29:32.425518Z","caller":"traceutil/trace.go:171","msg":"trace[1510211122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7689; }","duration":"128.230063ms","start":"2026-03-03T18:29:32.297257Z","end":"2026-03-03T18:29:32.425487Z","steps":["trace[1510211122] 'agreement among raft nodes before linearized reading'  (duration: 128.08036ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:29:32.425553Z","caller":"traceutil/trace.go:171","msg":"trace[1821277635] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:7689; }","duration":"167.839197ms","start":"2026-03-03T18:29:32.257691Z","end":"2026-03-03T18:29:32.425531Z","steps":["trace[1821277635] 'agreement among raft nodes before linearized reading'  (duration: 167.682913ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:29:41.676614Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5716}
{"level":"info","ts":"2026-03-03T18:29:41.75977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5716,"took":"81.278642ms","hash":2670599152,"current-db-size-bytes":43171840,"current-db-size":"43 MB","current-db-size-in-use-bytes":22999040,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-03-03T18:29:41.75983Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2670599152,"revision":5716,"compact-revision":2760}
{"level":"info","ts":"2026-03-03T18:29:59.438101Z","caller":"traceutil/trace.go:171","msg":"trace[1505610322] linearizableReadLoop","detail":"{readStateIndex:8164; appliedIndex:8163; }","duration":"233.106617ms","start":"2026-03-03T18:29:59.204975Z","end":"2026-03-03T18:29:59.438081Z","steps":["trace[1505610322] 'read index received'  (duration: 232.982295ms)","trace[1505610322] 'applied index is now lower than readState.Index'  (duration: 123.672µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-03T18:29:59.43823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.24067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:29:59.438258Z","caller":"traceutil/trace.go:171","msg":"trace[616975319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7872; }","duration":"233.316282ms","start":"2026-03-03T18:29:59.204935Z","end":"2026-03-03T18:29:59.438251Z","steps":["trace[616975319] 'agreement among raft nodes before linearized reading'  (duration: 233.25317ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:29:59.438231Z","caller":"traceutil/trace.go:171","msg":"trace[2137181766] transaction","detail":"{read_only:false; response_revision:7872; number_of_response:1; }","duration":"323.036825ms","start":"2026-03-03T18:29:59.115159Z","end":"2026-03-03T18:29:59.438196Z","steps":["trace[2137181766] 'process raft request'  (duration: 322.80759ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:29:59.438401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:29:59.11514Z","time spent":"323.167717ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:7861 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-03-03T18:29:59.599783Z","caller":"traceutil/trace.go:171","msg":"trace[895617924] linearizableReadLoop","detail":"{readStateIndex:8165; appliedIndex:8164; }","duration":"161.588093ms","start":"2026-03-03T18:29:59.438164Z","end":"2026-03-03T18:29:59.599752Z","steps":["trace[895617924] 'read index received'  (duration: 156.848782ms)","trace[895617924] 'applied index is now lower than readState.Index'  (duration: 4.73763ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:29:59.60018Z","caller":"traceutil/trace.go:171","msg":"trace[1407687455] transaction","detail":"{read_only:false; response_revision:7873; number_of_response:1; }","duration":"310.65394ms","start":"2026-03-03T18:29:59.289505Z","end":"2026-03-03T18:29:59.600159Z","steps":["trace[1407687455] 'process raft request'  (duration: 305.544071ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:29:59.600332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:29:59.289451Z","time spent":"310.788143ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:7862 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-03T18:29:59.601223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.641276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-03-03T18:29:59.601292Z","caller":"traceutil/trace.go:171","msg":"trace[491277381] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:7873; }","duration":"277.743188ms","start":"2026-03-03T18:29:59.323534Z","end":"2026-03-03T18:29:59.601277Z","steps":["trace[491277381] 'agreement among raft nodes before linearized reading'  (duration: 277.051134ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:30:13.781509Z","caller":"traceutil/trace.go:171","msg":"trace[1097384766] transaction","detail":"{read_only:false; response_revision:7986; number_of_response:1; }","duration":"208.252008ms","start":"2026-03-03T18:30:13.573231Z","end":"2026-03-03T18:30:13.781483Z","steps":["trace[1097384766] 'process raft request'  (duration: 160.534591ms)","trace[1097384766] 'compare'  (duration: 47.629845ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:30:13.825101Z","caller":"traceutil/trace.go:171","msg":"trace[1227214723] transaction","detail":"{read_only:false; response_revision:7987; number_of_response:1; }","duration":"174.033008ms","start":"2026-03-03T18:30:13.651043Z","end":"2026-03-03T18:30:13.825076Z","steps":["trace[1227214723] 'process raft request'  (duration: 173.856194ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:30:13.825297Z","caller":"traceutil/trace.go:171","msg":"trace[666962452] linearizableReadLoop","detail":"{readStateIndex:8287; appliedIndex:8283; }","duration":"101.713838ms","start":"2026-03-03T18:30:13.723561Z","end":"2026-03-03T18:30:13.825275Z","steps":["trace[666962452] 'read index received'  (duration: 10.215198ms)","trace[666962452] 'applied index is now lower than readState.Index'  (duration: 91.49761ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:30:13.825326Z","caller":"traceutil/trace.go:171","msg":"trace[1917254530] transaction","detail":"{read_only:false; response_revision:7988; number_of_response:1; }","duration":"169.402921ms","start":"2026-03-03T18:30:13.655898Z","end":"2026-03-03T18:30:13.825301Z","steps":["trace[1917254530] 'process raft request'  (duration: 169.139544ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:30:13.825346Z","caller":"traceutil/trace.go:171","msg":"trace[1010316479] transaction","detail":"{read_only:false; response_revision:7989; number_of_response:1; }","duration":"165.535698ms","start":"2026-03-03T18:30:13.659788Z","end":"2026-03-03T18:30:13.825323Z","steps":["trace[1010316479] 'process raft request'  (duration: 165.409215ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:30:13.825777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.205508ms","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-03T18:30:13.825856Z","caller":"traceutil/trace.go:171","msg":"trace[326807602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7989; }","duration":"102.326151ms","start":"2026-03-03T18:30:13.723514Z","end":"2026-03-03T18:30:13.82584Z","steps":["trace[326807602] 'agreement among raft nodes before linearized reading'  (duration: 101.854441ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:30:13.984405Z","caller":"traceutil/trace.go:171","msg":"trace[104562617] transaction","detail":"{read_only:false; response_revision:7992; number_of_response:1; }","duration":"154.200436ms","start":"2026-03-03T18:30:13.830179Z","end":"2026-03-03T18:30:13.984379Z","steps":["trace[104562617] 'process raft request'  (duration: 154.150295ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:30:13.984534Z","caller":"traceutil/trace.go:171","msg":"trace[549711573] transaction","detail":"{read_only:false; response_revision:7991; number_of_response:1; }","duration":"154.608124ms","start":"2026-03-03T18:30:13.82988Z","end":"2026-03-03T18:30:13.984488Z","steps":["trace[549711573] 'process raft request'  (duration: 154.312948ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:31:30.027065Z","caller":"traceutil/trace.go:171","msg":"trace[563437604] linearizableReadLoop","detail":"{readStateIndex:9011; appliedIndex:9010; }","duration":"224.844845ms","start":"2026-03-03T18:31:29.802189Z","end":"2026-03-03T18:31:30.027033Z","steps":["trace[563437604] 'read index received'  (duration: 179.827647ms)","trace[563437604] 'applied index is now lower than readState.Index'  (duration: 45.015658ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-03T18:31:30.027315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.106841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:31:30.027375Z","caller":"traceutil/trace.go:171","msg":"trace[1001716286] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8692; }","duration":"225.255173ms","start":"2026-03-03T18:31:29.802108Z","end":"2026-03-03T18:31:30.027363Z","steps":["trace[1001716286] 'agreement among raft nodes before linearized reading'  (duration: 225.127511ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:31:30.027415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.272977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-03-03T18:31:30.027447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.197105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:31:30.02753Z","caller":"traceutil/trace.go:171","msg":"trace[2079387546] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8692; }","duration":"187.367288ms","start":"2026-03-03T18:31:29.840142Z","end":"2026-03-03T18:31:30.027509Z","steps":["trace[2079387546] 'agreement among raft nodes before linearized reading'  (duration: 187.198945ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:31:30.027939Z","caller":"traceutil/trace.go:171","msg":"trace[658105882] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8692; }","duration":"204.409591ms","start":"2026-03-03T18:31:29.823071Z","end":"2026-03-03T18:31:30.02748Z","steps":["trace[658105882] 'agreement among raft nodes before linearized reading'  (duration: 204.250708ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:33:45.809011Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"53de2bdd9aa7b793","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-03-03T18:33:45.814039Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-03-03T18:33:45.814117Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-03-03T18:34:41.684889Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7742}
{"level":"info","ts":"2026-03-03T18:34:41.75233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7742,"took":"65.072596ms","hash":2373121953,"current-db-size-bytes":43171840,"current-db-size":"43 MB","current-db-size-in-use-bytes":27181056,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-03-03T18:34:41.752439Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2373121953,"revision":7742,"compact-revision":5716}
{"level":"info","ts":"2026-03-03T18:35:17.517538Z","caller":"traceutil/trace.go:171","msg":"trace[250742611] transaction","detail":"{read_only:false; response_revision:10449; number_of_response:1; }","duration":"101.42796ms","start":"2026-03-03T18:35:17.416073Z","end":"2026-03-03T18:35:17.517501Z","steps":["trace[250742611] 'process raft request'  (duration: 101.200646ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:35:17.535497Z","caller":"traceutil/trace.go:171","msg":"trace[275669651] transaction","detail":"{read_only:false; response_revision:10450; number_of_response:1; }","duration":"118.448473ms","start":"2026-03-03T18:35:17.417028Z","end":"2026-03-03T18:35:17.535477Z","steps":["trace[275669651] 'process raft request'  (duration: 118.32503ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:39:41.693027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10211}
{"level":"info","ts":"2026-03-03T18:39:41.779855Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10211,"took":"84.080813ms","hash":17356681,"current-db-size-bytes":43171840,"current-db-size":"43 MB","current-db-size-in-use-bytes":23564288,"current-db-size-in-use":"24 MB"}
{"level":"info","ts":"2026-03-03T18:39:41.77995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":17356681,"revision":10211,"compact-revision":7742}
{"level":"info","ts":"2026-03-03T18:41:37.368311Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000033e1.wal"}
{"level":"info","ts":"2026-03-03T18:41:44.780316Z","caller":"traceutil/trace.go:171","msg":"trace[631381963] transaction","detail":"{read_only:false; response_revision:12854; number_of_response:1; }","duration":"196.897497ms","start":"2026-03-03T18:41:44.583326Z","end":"2026-03-03T18:41:44.780224Z","steps":["trace[631381963] 'process raft request'  (duration: 195.18955ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:41:51.731219Z","caller":"traceutil/trace.go:171","msg":"trace[471308179] transaction","detail":"{read_only:false; response_revision:12900; number_of_response:1; }","duration":"115.957456ms","start":"2026-03-03T18:41:51.615216Z","end":"2026-03-03T18:41:51.731173Z","steps":["trace[471308179] 'process raft request'  (duration: 60.537998ms)","trace[471308179] 'compare'  (duration: 55.263905ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:41:51.779368Z","caller":"traceutil/trace.go:171","msg":"trace[893509309] transaction","detail":"{read_only:false; response_revision:12901; number_of_response:1; }","duration":"153.76531ms","start":"2026-03-03T18:41:51.625558Z","end":"2026-03-03T18:41:51.779323Z","steps":["trace[893509309] 'process raft request'  (duration: 153.144397ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:41:52.745804Z","caller":"traceutil/trace.go:171","msg":"trace[2126946752] transaction","detail":"{read_only:false; response_revision:12907; number_of_response:1; }","duration":"169.33176ms","start":"2026-03-03T18:41:52.576442Z","end":"2026-03-03T18:41:52.745774Z","steps":["trace[2126946752] 'process raft request'  (duration: 169.167366ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:41:53.024737Z","caller":"traceutil/trace.go:171","msg":"trace[896634347] linearizableReadLoop","detail":"{readStateIndex:13375; appliedIndex:13374; }","duration":"259.200672ms","start":"2026-03-03T18:41:52.765505Z","end":"2026-03-03T18:41:53.024706Z","steps":["trace[896634347] 'read index received'  (duration: 165.936358ms)","trace[896634347] 'applied index is now lower than readState.Index'  (duration: 93.262624ms)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:41:53.024752Z","caller":"traceutil/trace.go:171","msg":"trace[993871343] transaction","detail":"{read_only:false; response_revision:12908; number_of_response:1; }","duration":"424.087747ms","start":"2026-03-03T18:41:52.600629Z","end":"2026-03-03T18:41:53.024716Z","steps":["trace[993871343] 'process raft request'  (duration: 330.793633ms)","trace[993871343] 'compare'  (duration: 93.078629ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-03T18:41:53.024898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.349275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:41:53.024974Z","caller":"traceutil/trace.go:171","msg":"trace[789723615] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:12908; }","duration":"259.502298ms","start":"2026-03-03T18:41:52.76545Z","end":"2026-03-03T18:41:53.024952Z","steps":["trace[789723615] 'agreement among raft nodes before linearized reading'  (duration: 259.342645ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:41:53.025014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:41:52.600605Z","time spent":"424.306632ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:12882 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-03-03T18:41:53.030437Z","caller":"traceutil/trace.go:171","msg":"trace[624184298] transaction","detail":"{read_only:false; response_revision:12909; number_of_response:1; }","duration":"222.227626ms","start":"2026-03-03T18:41:52.808176Z","end":"2026-03-03T18:41:53.030403Z","steps":["trace[624184298] 'process raft request'  (duration: 222.087033ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:41:53.031481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.050665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-03-03T18:41:53.031542Z","caller":"traceutil/trace.go:171","msg":"trace[1907000760] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:12909; }","duration":"186.134227ms","start":"2026-03-03T18:41:52.845393Z","end":"2026-03-03T18:41:53.031527Z","steps":["trace[1907000760] 'agreement among raft nodes before linearized reading'  (duration: 184.975212ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:42:02.526481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.764549ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13228088831330957485 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/openstack/placement-api-7d7b6bc66b-6m8nl.189968e7cdd3a70f\" mod_revision:12944 > success:<request_put:<key:\"/registry/events/openstack/placement-api-7d7b6bc66b-6m8nl.189968e7cdd3a70f\" value_size:764 lease:4004716794476180449 >> failure:<request_range:<key:\"/registry/events/openstack/placement-api-7d7b6bc66b-6m8nl.189968e7cdd3a70f\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-03T18:42:02.526618Z","caller":"traceutil/trace.go:171","msg":"trace[1962614238] transaction","detail":"{read_only:false; response_revision:13074; number_of_response:1; }","duration":"464.682329ms","start":"2026-03-03T18:42:02.061914Z","end":"2026-03-03T18:42:02.526596Z","steps":["trace[1962614238] 'process raft request'  (duration: 151.692455ms)","trace[1962614238] 'compare'  (duration: 311.769899ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-03T18:42:02.52668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:42:02.061898Z","time spent":"464.75263ms","remote":"127.0.0.1:52906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":856,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/events/openstack/placement-api-7d7b6bc66b-6m8nl.189968e7cdd3a70f\" mod_revision:12944 > success:<request_put:<key:\"/registry/events/openstack/placement-api-7d7b6bc66b-6m8nl.189968e7cdd3a70f\" value_size:764 lease:4004716794476180449 >> failure:<request_range:<key:\"/registry/events/openstack/placement-api-7d7b6bc66b-6m8nl.189968e7cdd3a70f\" > >"}
{"level":"info","ts":"2026-03-03T18:42:02.565407Z","caller":"traceutil/trace.go:171","msg":"trace[2140960248] linearizableReadLoop","detail":"{readStateIndex:13545; appliedIndex:13543; }","duration":"476.860299ms","start":"2026-03-03T18:42:02.088515Z","end":"2026-03-03T18:42:02.565375Z","steps":["trace[2140960248] 'read index received'  (duration: 125.110011ms)","trace[2140960248] 'applied index is now lower than readState.Index'  (duration: 351.748928ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-03T18:42:02.56559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.044383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-03T18:42:02.565634Z","caller":"traceutil/trace.go:171","msg":"trace[853866935] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:13075; }","duration":"477.142945ms","start":"2026-03-03T18:42:02.088478Z","end":"2026-03-03T18:42:02.565621Z","steps":["trace[853866935] 'agreement among raft nodes before linearized reading'  (duration: 477.040773ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:42:02.565682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:42:02.088459Z","time spent":"477.211366ms","remote":"127.0.0.1:33986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "}
{"level":"info","ts":"2026-03-03T18:42:02.565877Z","caller":"traceutil/trace.go:171","msg":"trace[24588299] transaction","detail":"{read_only:false; response_revision:13075; number_of_response:1; }","duration":"500.069422ms","start":"2026-03-03T18:42:02.065744Z","end":"2026-03-03T18:42:02.565813Z","steps":["trace[24588299] 'process raft request'  (duration: 499.46593ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:42:02.56611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:42:02.065724Z","time spent":"500.264927ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8792,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/placement-api-7d7b6bc66b-6m8nl\" mod_revision:12945 > success:<request_put:<key:\"/registry/pods/openstack/placement-api-7d7b6bc66b-6m8nl\" value_size:8729 >> failure:<request_range:<key:\"/registry/pods/openstack/placement-api-7d7b6bc66b-6m8nl\" > >"}
{"level":"warn","ts":"2026-03-03T18:42:02.572931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.120961ms","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-03-03T18:42:02.573025Z","caller":"traceutil/trace.go:171","msg":"trace[970795568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13076; }","duration":"459.238844ms","start":"2026-03-03T18:42:02.113768Z","end":"2026-03-03T18:42:02.573007Z","steps":["trace[970795568] 'agreement among raft nodes before linearized reading'  (duration: 459.01066ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:42:02.573064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:42:02.113736Z","time spent":"459.320186ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-03-03T18:43:36.628288Z","caller":"traceutil/trace.go:171","msg":"trace[1973679172] linearizableReadLoop","detail":"{readStateIndex:14604; appliedIndex:14603; }","duration":"107.616949ms","start":"2026-03-03T18:43:36.520649Z","end":"2026-03-03T18:43:36.628266Z","steps":["trace[1973679172] 'read index received'  (duration: 96.812799ms)","trace[1973679172] 'applied index is now lower than readState.Index'  (duration: 10.80302ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-03T18:43:36.628513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.859044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6bgxc\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-03-03T18:43:36.628551Z","caller":"traceutil/trace.go:171","msg":"trace[1250852607] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6bgxc; range_end:; response_count:1; response_revision:14098; }","duration":"107.952286ms","start":"2026-03-03T18:43:36.520587Z","end":"2026-03-03T18:43:36.628539Z","steps":["trace[1250852607] 'agreement among raft nodes before linearized reading'  (duration: 107.804103ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:43:36.628323Z","caller":"traceutil/trace.go:171","msg":"trace[2094864057] transaction","detail":"{read_only:false; response_revision:14098; number_of_response:1; }","duration":"197.920879ms","start":"2026-03-03T18:43:36.430375Z","end":"2026-03-03T18:43:36.628296Z","steps":["trace[2094864057] 'process raft request'  (duration: 187.031497ms)","trace[2094864057] 'compare'  (duration: 10.482353ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-03T18:43:36.91139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.292056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:43:36.911477Z","caller":"traceutil/trace.go:171","msg":"trace[1238551255] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14098; }","duration":"150.398008ms","start":"2026-03-03T18:43:36.761059Z","end":"2026-03-03T18:43:36.911457Z","steps":["trace[1238551255] 'range keys from in-memory index tree'  (duration: 150.191033ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:36.911466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.503009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"}
{"level":"warn","ts":"2026-03-03T18:43:36.911503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.842321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"}
{"level":"info","ts":"2026-03-03T18:43:36.911581Z","caller":"traceutil/trace.go:171","msg":"trace[983374363] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14098; }","duration":"149.670013ms","start":"2026-03-03T18:43:36.761888Z","end":"2026-03-03T18:43:36.911558Z","steps":["trace[983374363] 'range keys from in-memory index tree'  (duration: 149.283805ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:43:36.911609Z","caller":"traceutil/trace.go:171","msg":"trace[773176822] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14098; }","duration":"146.011665ms","start":"2026-03-03T18:43:36.765573Z","end":"2026-03-03T18:43:36.911584Z","steps":["trace[773176822] 'range keys from in-memory index tree'  (duration: 145.637846ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:43:37.112392Z","caller":"traceutil/trace.go:171","msg":"trace[294015835] linearizableReadLoop","detail":"{readStateIndex:14605; appliedIndex:14604; }","duration":"242.328703ms","start":"2026-03-03T18:43:36.870038Z","end":"2026-03-03T18:43:37.112367Z","steps":["trace[294015835] 'read index received'  (duration: 242.16986ms)","trace[294015835] 'applied index is now lower than readState.Index'  (duration: 157.753µs)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:43:37.112712Z","caller":"traceutil/trace.go:171","msg":"trace[1794285036] transaction","detail":"{read_only:false; response_revision:14099; number_of_response:1; }","duration":"263.440251ms","start":"2026-03-03T18:43:36.849257Z","end":"2026-03-03T18:43:37.112698Z","steps":["trace[1794285036] 'process raft request'  (duration: 262.971291ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.112963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.920645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"}
{"level":"info","ts":"2026-03-03T18:43:37.113011Z","caller":"traceutil/trace.go:171","msg":"trace[734476762] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14099; }","duration":"243.025887ms","start":"2026-03-03T18:43:36.869973Z","end":"2026-03-03T18:43:37.112999Z","steps":["trace[734476762] 'agreement among raft nodes before linearized reading'  (duration: 242.876534ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.113234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.882045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:43:37.113276Z","caller":"traceutil/trace.go:171","msg":"trace[933331998] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14099; }","duration":"242.943126ms","start":"2026-03-03T18:43:36.870322Z","end":"2026-03-03T18:43:37.113265Z","steps":["trace[933331998] 'agreement among raft nodes before linearized reading'  (duration: 242.881044ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.113425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.980925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:43:37.113471Z","caller":"traceutil/trace.go:171","msg":"trace[530362183] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14099; }","duration":"131.028456ms","start":"2026-03-03T18:43:36.982433Z","end":"2026-03-03T18:43:37.113461Z","steps":["trace[530362183] 'agreement among raft nodes before linearized reading'  (duration: 130.963864ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.113507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.256412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"}
{"level":"info","ts":"2026-03-03T18:43:37.113538Z","caller":"traceutil/trace.go:171","msg":"trace[1929967936] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14099; }","duration":"219.342043ms","start":"2026-03-03T18:43:36.894187Z","end":"2026-03-03T18:43:37.113529Z","steps":["trace[1929967936] 'agreement among raft nodes before linearized reading'  (duration: 219.262151ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.113433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.305902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:43:37.113687Z","caller":"traceutil/trace.go:171","msg":"trace[1840752213] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14099; }","duration":"131.617338ms","start":"2026-03-03T18:43:36.982061Z","end":"2026-03-03T18:43:37.113678Z","steps":["trace[1840752213] 'agreement among raft nodes before linearized reading'  (duration: 131.349663ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.113823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.513995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"}
{"level":"info","ts":"2026-03-03T18:43:37.113845Z","caller":"traceutil/trace.go:171","msg":"trace[2140410831] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14099; }","duration":"130.561887ms","start":"2026-03-03T18:43:36.983278Z","end":"2026-03-03T18:43:37.11384Z","steps":["trace[2140410831] 'agreement among raft nodes before linearized reading'  (duration: 130.498245ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.113909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.365213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:43:37.113928Z","caller":"traceutil/trace.go:171","msg":"trace[1849768599] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14099; }","duration":"131.437494ms","start":"2026-03-03T18:43:36.982486Z","end":"2026-03-03T18:43:37.113923Z","steps":["trace[1849768599] 'agreement among raft nodes before linearized reading'  (duration: 131.406994ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:43:37.471582Z","caller":"traceutil/trace.go:171","msg":"trace[1666924554] linearizableReadLoop","detail":"{readStateIndex:14606; appliedIndex:14605; }","duration":"323.405607ms","start":"2026-03-03T18:43:37.148145Z","end":"2026-03-03T18:43:37.471551Z","steps":["trace[1666924554] 'read index received'  (duration: 323.392606ms)","trace[1666924554] 'applied index is now lower than readState.Index'  (duration: 11.24µs)"],"step_count":2}
{"level":"info","ts":"2026-03-03T18:43:37.471583Z","caller":"traceutil/trace.go:171","msg":"trace[417841957] transaction","detail":"{read_only:false; response_revision:14100; number_of_response:1; }","duration":"354.331015ms","start":"2026-03-03T18:43:37.117225Z","end":"2026-03-03T18:43:37.471557Z","steps":["trace[417841957] 'process raft request'  (duration: 354.192162ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.471743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:43:37.117199Z","time spent":"354.464577ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:14091 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-03-03T18:43:37.471728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.563471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:43:37.471821Z","caller":"traceutil/trace.go:171","msg":"trace[1038113443] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14100; }","duration":"323.699853ms","start":"2026-03-03T18:43:37.148098Z","end":"2026-03-03T18:43:37.471798Z","steps":["trace[1038113443] 'agreement among raft nodes before linearized reading'  (duration: 323.537519ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.471863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:43:37.148078Z","time spent":"323.776275ms","remote":"127.0.0.1:48218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "}
{"level":"warn","ts":"2026-03-03T18:43:37.485463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.262122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-03T18:43:37.48548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.755803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-03T18:43:37.485546Z","caller":"traceutil/trace.go:171","msg":"trace[708759898] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14101; }","duration":"337.411395ms","start":"2026-03-03T18:43:37.148124Z","end":"2026-03-03T18:43:37.485535Z","steps":["trace[708759898] 'agreement among raft nodes before linearized reading'  (duration: 337.239971ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:43:37.485528Z","caller":"traceutil/trace.go:171","msg":"trace[918939422] transaction","detail":"{read_only:false; response_revision:14101; number_of_response:1; }","duration":"331.667842ms","start":"2026-03-03T18:43:37.153799Z","end":"2026-03-03T18:43:37.485467Z","steps":["trace[918939422] 'process raft request'  (duration: 331.464448ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:43:37.485557Z","caller":"traceutil/trace.go:171","msg":"trace[47643075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14101; }","duration":"188.866366ms","start":"2026-03-03T18:43:37.296679Z","end":"2026-03-03T18:43:37.485545Z","steps":["trace[47643075] 'agreement among raft nodes before linearized reading'  (duration: 188.747924ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.485576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:43:37.148098Z","time spent":"337.471386ms","remote":"127.0.0.1:48218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "}
{"level":"warn","ts":"2026-03-03T18:43:37.485677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:43:37.153783Z","time spent":"331.807856ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:14092 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-03T18:43:37.485805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.8344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"}
{"level":"warn","ts":"2026-03-03T18:43:37.485823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.742895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-f8rjz\" ","response":"range_response_count:1 size:9788"}
{"level":"info","ts":"2026-03-03T18:43:37.485885Z","caller":"traceutil/trace.go:171","msg":"trace[1429080823] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-f8rjz; range_end:; response_count:1; response_revision:14101; }","duration":"324.823927ms","start":"2026-03-03T18:43:37.161047Z","end":"2026-03-03T18:43:37.485871Z","steps":["trace[1429080823] 'agreement among raft nodes before linearized reading'  (duration: 324.642573ms)"],"step_count":1}
{"level":"info","ts":"2026-03-03T18:43:37.485885Z","caller":"traceutil/trace.go:171","msg":"trace[1354555960] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14101; }","duration":"334.926782ms","start":"2026-03-03T18:43:37.150938Z","end":"2026-03-03T18:43:37.485864Z","steps":["trace[1354555960] 'agreement among raft nodes before linearized reading'  (duration: 334.710447ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.485919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:43:37.161037Z","time spent":"324.875178ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9810,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-f8rjz\" "}
{"level":"warn","ts":"2026-03-03T18:43:37.4858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.492855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-03T18:43:37.485935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:43:37.150928Z","time spent":"334.994443ms","remote":"127.0.0.1:48218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "}
{"level":"info","ts":"2026-03-03T18:43:37.48596Z","caller":"traceutil/trace.go:171","msg":"trace[373088315] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14101; }","duration":"336.713539ms","start":"2026-03-03T18:43:37.149237Z","end":"2026-03-03T18:43:37.485951Z","steps":["trace[373088315] 'agreement among raft nodes before linearized reading'  (duration: 336.456314ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:43:37.485985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:43:37.14917Z","time spent":"336.808241ms","remote":"127.0.0.1:48218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "}
{"level":"info","ts":"2026-03-03T18:44:41.698763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12125}
{"level":"info","ts":"2026-03-03T18:44:41.782917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12125,"took":"81.269778ms","hash":577670400,"current-db-size-bytes":43171840,"current-db-size":"43 MB","current-db-size-in-use-bytes":27213824,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-03-03T18:44:41.782977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":577670400,"revision":12125,"compact-revision":10211}
{"level":"warn","ts":"2026-03-03T18:46:47.604461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.192233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"}
{"level":"info","ts":"2026-03-03T18:46:47.604554Z","caller":"traceutil/trace.go:171","msg":"trace[421744136] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15471; }","duration":"101.365957ms","start":"2026-03-03T18:46:47.503167Z","end":"2026-03-03T18:46:47.604533Z","steps":["trace[421744136] 'agreement among raft nodes before linearized reading'  (duration: 100.941588ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:49:28.297355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.54046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-03T18:49:28.297522Z","caller":"traceutil/trace.go:171","msg":"trace[1362372171] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:16455; }","duration":"148.783936ms","start":"2026-03-03T18:49:28.148708Z","end":"2026-03-03T18:49:28.297492Z","steps":["trace[1362372171] 'count revisions from in-memory index tree'  (duration: 148.349845ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:49:29.529131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.704199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kh8xn\" ","response":"range_response_count:1 size:22094"}
{"level":"info","ts":"2026-03-03T18:49:29.529232Z","caller":"traceutil/trace.go:171","msg":"trace[1998030063] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kh8xn; range_end:; response_count:1; response_revision:16463; }","duration":"302.875663ms","start":"2026-03-03T18:49:29.226331Z","end":"2026-03-03T18:49:29.529207Z","steps":["trace[1998030063] 'range keys from in-memory index tree'  (duration: 302.473504ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:49:29.529278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:49:29.226304Z","time spent":"302.965515ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22116,"request content":"key:\"/registry/pods/openstack/nova-compute-default-kh8xn\" "}
{"level":"warn","ts":"2026-03-03T18:49:29.529415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.879755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"}
{"level":"info","ts":"2026-03-03T18:49:29.529483Z","caller":"traceutil/trace.go:171","msg":"trace[1684974225] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16463; }","duration":"242.029458ms","start":"2026-03-03T18:49:29.287437Z","end":"2026-03-03T18:49:29.529466Z","steps":["trace[1684974225] 'range keys from in-memory index tree'  (duration: 241.700632ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:49:29.529705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.79169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-03T18:49:29.529756Z","caller":"traceutil/trace.go:171","msg":"trace[450732296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16463; }","duration":"231.873042ms","start":"2026-03-03T18:49:29.297874Z","end":"2026-03-03T18:49:29.529747Z","steps":["trace[450732296] 'range keys from in-memory index tree'  (duration: 231.735869ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:49:29.529962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.45371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"}
{"level":"info","ts":"2026-03-03T18:49:29.529998Z","caller":"traceutil/trace.go:171","msg":"trace[1253420987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16463; }","duration":"119.538253ms","start":"2026-03-03T18:49:29.41045Z","end":"2026-03-03T18:49:29.529988Z","steps":["trace[1253420987] 'range keys from in-memory index tree'  (duration: 119.329188ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:49:29.530031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.46974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"}
{"level":"info","ts":"2026-03-03T18:49:29.530069Z","caller":"traceutil/trace.go:171","msg":"trace[930924932] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16463; }","duration":"431.564032ms","start":"2026-03-03T18:49:29.098494Z","end":"2026-03-03T18:49:29.530058Z","steps":["trace[930924932] 'range keys from in-memory index tree'  (duration: 431.350587ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-03T18:49:29.530127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T18:49:29.098442Z","time spent":"431.675254ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":722,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "}
{"level":"info","ts":"2026-03-03T18:49:41.70633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14466}
{"level":"info","ts":"2026-03-03T18:49:41.814607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14466,"took":"104.033933ms","hash":945495507,"current-db-size-bytes":43593728,"current-db-size":"44 MB","current-db-size-in-use-bytes":28319744,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-03-03T18:49:41.814667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":945495507,"revision":14466,"compact-revision":12125}
{"level":"info","ts":"2026-03-03T18:54:41.715738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16593}
{"level":"info","ts":"2026-03-03T18:54:41.812369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16593,"took":"93.280111ms","hash":2652165607,"current-db-size-bytes":43593728,"current-db-size":"44 MB","current-db-size-in-use-bytes":22814720,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-03-03T18:54:41.81243Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2652165607,"revision":16593,"compact-revision":14466}