{"level":"warn","ts":"2026-09-10T01:49:34.889557Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T01:49:34.889679Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.71: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.71:2380","--initial-cluster=instance=https://162.253.55.71:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.71:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.71: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-09-10T01:49:34.889881Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T01:49:34.889903Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-09-10T01:49:34.890011Z","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-09-10T01:49:34.890851Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"]} {"level":"info","ts":"2026-09-10T01:49:34.891005Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.71: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-09-10T01:49:34.895883Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.577528ms"} {"level":"info","ts":"2026-09-10T01:49:34.903465Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"54232768b5552f01","cluster-id":"66db39a47e9fb23b"} {"level":"info","ts":"2026-09-10T01:49:34.903546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T01:49:34.903564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 0"} {"level":"info","ts":"2026-09-10T01:49:34.903571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 54232768b5552f01 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T01:49:34.903577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 1"} {"level":"info","ts":"2026-09-10T01:49:34.903775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"warn","ts":"2026-09-10T01:49:34.91188Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T01:49:34.913557Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T01:49:34.915062Z","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-09-10T01:49:34.917271Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"54232768b5552f01","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T01:49:34.920813Z","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-09-10T01:49:34.920921Z","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-09-10T01:49:34.920934Z","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-09-10T01:49:34.92109Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"54232768b5552f01","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T01:49:34.921755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"info","ts":"2026-09-10T01:49:34.928774Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","added-peer-id":"54232768b5552f01","added-peer-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-09-10T01:49:34.930152Z","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-09-10T01:49:34.930275Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-09-10T01:49:34.930384Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"54232768b5552f01","initial-advertise-peer-urls":["https://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T01:49:34.930413Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T01:49:34.930314Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-09-10T01:49:35.704161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T01:49:35.704209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T01:49:35.704234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgPreVoteResp from 54232768b5552f01 at term 1"} {"level":"info","ts":"2026-09-10T01:49:35.704244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became candidate at term 2"} {"level":"info","ts":"2026-09-10T01:49:35.704249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgVoteResp from 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-09-10T01:49:35.704258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became leader at term 2"} {"level":"info","ts":"2026-09-10T01:49:35.704264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 54232768b5552f01 elected leader 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-09-10T01:49:35.70512Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"54232768b5552f01","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.71:2379]}","request-path":"/0/members/54232768b5552f01/attributes","cluster-id":"66db39a47e9fb23b","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T01:49:35.705132Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:49:35.705208Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:49:35.705244Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:49:35.705363Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T01:49:35.70541Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T01:49:35.706115Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:49:35.706227Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:49:35.706254Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:49:35.709842Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T01:49:35.709878Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.71:2379"} {"level":"info","ts":"2026-09-10T01:50:00.649951Z","caller":"traceutil/trace.go:171","msg":"trace[171368667] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"104.28454ms","start":"2026-09-10T01:50:00.545575Z","end":"2026-09-10T01:50:00.64986Z","steps":["trace[171368667] 'process raft request' (duration: 104.138294ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.65836Z","caller":"traceutil/trace.go:171","msg":"trace[1966857622] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"206.209675ms","start":"2026-09-10T01:50:16.452129Z","end":"2026-09-10T01:50:16.658338Z","steps":["trace[1966857622] 'process raft request' (duration: 206.080901ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.683603Z","caller":"traceutil/trace.go:171","msg":"trace[661786021] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"198.727655ms","start":"2026-09-10T01:50:16.48484Z","end":"2026-09-10T01:50:16.683567Z","steps":["trace[661786021] 'process raft request' (duration: 198.414624ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.683684Z","caller":"traceutil/trace.go:171","msg":"trace[431325019] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"198.732766ms","start":"2026-09-10T01:50:16.48492Z","end":"2026-09-10T01:50:16.683653Z","steps":["trace[431325019] 'process raft request' (duration: 198.471726ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.683912Z","caller":"traceutil/trace.go:171","msg":"trace[1744430692] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"183.192829ms","start":"2026-09-10T01:50:16.500689Z","end":"2026-09-10T01:50:16.683882Z","steps":["trace[1744430692] 'process raft request' (duration: 182.795643ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.684117Z","caller":"traceutil/trace.go:171","msg":"trace[1252154537] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"177.084448ms","start":"2026-09-10T01:50:16.507017Z","end":"2026-09-10T01:50:16.684101Z","steps":["trace[1252154537] 'process raft request' (duration: 176.616382ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.684234Z","caller":"traceutil/trace.go:171","msg":"trace[738267750] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"174.220515ms","start":"2026-09-10T01:50:16.510005Z","end":"2026-09-10T01:50:16.684226Z","steps":["trace[738267750] 'process raft request' (duration: 173.905474ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.684544Z","caller":"traceutil/trace.go:171","msg":"trace[1780380970] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"174.35806ms","start":"2026-09-10T01:50:16.510172Z","end":"2026-09-10T01:50:16.68453Z","steps":["trace[1780380970] 'process raft request' (duration: 173.970566ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.684706Z","caller":"traceutil/trace.go:171","msg":"trace[1686595043] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"173.654935ms","start":"2026-09-10T01:50:16.511025Z","end":"2026-09-10T01:50:16.68468Z","steps":["trace[1686595043] 'process raft request' (duration: 173.349884ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.685049Z","caller":"traceutil/trace.go:171","msg":"trace[1590333415] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"172.844356ms","start":"2026-09-10T01:50:16.512192Z","end":"2026-09-10T01:50:16.685036Z","steps":["trace[1590333415] 'process raft request' (duration: 172.505954ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.685162Z","caller":"traceutil/trace.go:171","msg":"trace[1438544740] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"156.625882ms","start":"2026-09-10T01:50:16.528528Z","end":"2026-09-10T01:50:16.685154Z","steps":["trace[1438544740] 'process raft request' (duration: 156.430465ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:16.685407Z","caller":"traceutil/trace.go:171","msg":"trace[1791961546] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"137.138482ms","start":"2026-09-10T01:50:16.548258Z","end":"2026-09-10T01:50:16.685397Z","steps":["trace[1791961546] 'process raft request' (duration: 136.82588ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:22.812707Z","caller":"traceutil/trace.go:171","msg":"trace[1699256254] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"178.63934ms","start":"2026-09-10T01:50:22.634041Z","end":"2026-09-10T01:50:22.81268Z","steps":["trace[1699256254] 'process raft request' (duration: 178.447383ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:23.217452Z","caller":"traceutil/trace.go:171","msg":"trace[892474136] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"375.309868ms","start":"2026-09-10T01:50:22.842115Z","end":"2026-09-10T01:50:23.217425Z","steps":["trace[892474136] 'read index received' (duration: 374.954336ms)","trace[892474136] 'applied index is now lower than readState.Index' (duration: 354.612µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:23.217517Z","caller":"traceutil/trace.go:171","msg":"trace[582309602] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"413.739408ms","start":"2026-09-10T01:50:22.803745Z","end":"2026-09-10T01:50:23.217484Z","steps":["trace[582309602] 'process raft request' (duration: 411.964318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:23.217843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.541331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-10T01:50:23.218Z","caller":"traceutil/trace.go:171","msg":"trace[739772940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"363.744327ms","start":"2026-09-10T01:50:22.854233Z","end":"2026-09-10T01:50:23.217977Z","steps":["trace[739772940] 'agreement among raft nodes before linearized reading' (duration: 363.433437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:23.217884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.758673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-10T01:50:23.218075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:22.854138Z","time spent":"363.923303ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-10T01:50:23.218144Z","caller":"traceutil/trace.go:171","msg":"trace[123036831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"376.061273ms","start":"2026-09-10T01:50:22.842064Z","end":"2026-09-10T01:50:23.218125Z","steps":["trace[123036831] 'agreement among raft nodes before linearized reading' (duration: 375.485164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:23.218195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:22.803712Z","time spent":"413.878582ms","remote":"127.0.0.1:36306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T01:50:23.21821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:22.842047Z","time spent":"376.146455ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-10T01:50:23.948075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.733688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:50:23.948371Z","caller":"traceutil/trace.go:171","msg":"trace[1784903112] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"431.928819ms","start":"2026-09-10T01:50:23.516426Z","end":"2026-09-10T01:50:23.948355Z","steps":["trace[1784903112] 'read index received' (duration: 208.070045ms)","trace[1784903112] 'applied index is now lower than readState.Index' (duration: 223.857904ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:23.948504Z","caller":"traceutil/trace.go:171","msg":"trace[1200686961] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"724.614979ms","start":"2026-09-10T01:50:23.22384Z","end":"2026-09-10T01:50:23.948455Z","steps":["trace[1200686961] 'process raft request' (duration: 81.187218ms)","trace[1200686961] 'compare' (duration: 642.560871ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:50:23.948555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.162518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:50:23.948535Z","caller":"traceutil/trace.go:171","msg":"trace[1071325254] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"660.400949ms","start":"2026-09-10T01:50:23.288097Z","end":"2026-09-10T01:50:23.948498Z","steps":["trace[1071325254] 'process raft request' (duration: 660.196162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:23.9487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:23.223825Z","time spent":"724.778174ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:50:23.948615Z","caller":"traceutil/trace.go:171","msg":"trace[843226574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"432.258171ms","start":"2026-09-10T01:50:23.516342Z","end":"2026-09-10T01:50:23.9486Z","steps":["trace[843226574] 'agreement among raft nodes before linearized reading' (duration: 432.155737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:23.948729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:23.288079Z","time spent":"660.594405ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T01:50:23.948751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.409521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-10T01:50:23.949351Z","caller":"traceutil/trace.go:171","msg":"trace[1699575818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"236.031751ms","start":"2026-09-10T01:50:23.71329Z","end":"2026-09-10T01:50:23.949322Z","steps":["trace[1699575818] 'agreement among raft nodes before linearized reading' (duration: 235.255316ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:23.948954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:23.516321Z","time spent":"432.608442ms","remote":"127.0.0.1:36202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-10T01:50:38.204501Z","caller":"traceutil/trace.go:171","msg":"trace[1444285103] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"149.733982ms","start":"2026-09-10T01:50:38.054745Z","end":"2026-09-10T01:50:38.204479Z","steps":["trace[1444285103] 'process raft request' (duration: 149.486355ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:38.208587Z","caller":"traceutil/trace.go:171","msg":"trace[502181354] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"153.050628ms","start":"2026-09-10T01:50:38.055509Z","end":"2026-09-10T01:50:38.20856Z","steps":["trace[502181354] 'process raft request' (duration: 152.666337ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:43.470281Z","caller":"traceutil/trace.go:171","msg":"trace[1322415628] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"111.555892ms","start":"2026-09-10T01:50:43.358691Z","end":"2026-09-10T01:50:43.470247Z","steps":["trace[1322415628] 'process raft request' (duration: 111.400248ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:01.439537Z","caller":"traceutil/trace.go:171","msg":"trace[1607042504] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"132.007539ms","start":"2026-09-10T01:52:01.307501Z","end":"2026-09-10T01:52:01.439509Z","steps":["trace[1607042504] 'process raft request' (duration: 131.807365ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:01.905457Z","caller":"traceutil/trace.go:171","msg":"trace[560378669] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"590.330376ms","start":"2026-09-10T01:52:01.315064Z","end":"2026-09-10T01:52:01.905395Z","steps":["trace[560378669] 'process raft request' (duration: 581.861934ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:01.905932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:01.315003Z","time spent":"590.820854ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T01:52:02.263217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.671263ms","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-09-10T01:52:02.263351Z","caller":"traceutil/trace.go:171","msg":"trace[1900480893] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1888; }","duration":"170.817626ms","start":"2026-09-10T01:52:02.092512Z","end":"2026-09-10T01:52:02.26333Z","steps":["trace[1900480893] 'range keys from in-memory index tree' (duration: 170.636493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:02.263654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.542818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:52:02.263835Z","caller":"traceutil/trace.go:171","msg":"trace[1584290740] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"157.474823ms","start":"2026-09-10T01:52:02.106344Z","end":"2026-09-10T01:52:02.263819Z","steps":["trace[1584290740] 'read index received' (duration: 27.931µs)","trace[1584290740] 'applied index is now lower than readState.Index' (duration: 157.445252ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:02.264217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.86782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-10T01:52:02.264248Z","caller":"traceutil/trace.go:171","msg":"trace[146952243] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1889; }","duration":"157.92586ms","start":"2026-09-10T01:52:02.106313Z","end":"2026-09-10T01:52:02.264239Z","steps":["trace[146952243] 'agreement among raft nodes before linearized reading' (duration: 157.823189ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:02.264332Z","caller":"traceutil/trace.go:171","msg":"trace[641511682] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"344.289772ms","start":"2026-09-10T01:52:01.920013Z","end":"2026-09-10T01:52:02.264303Z","steps":["trace[641511682] 'process raft request' (duration: 151.036283ms)","trace[641511682] 'compare' (duration: 192.455396ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:02.264526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:01.919997Z","time spent":"344.447305ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T01:52:02.264622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.313577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b904309-2b02-4989-9b66-e00fe3b7a0e5\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-10T01:52:02.264675Z","caller":"traceutil/trace.go:171","msg":"trace[740081298] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b904309-2b02-4989-9b66-e00fe3b7a0e5; range_end:; response_count:1; response_revision:1889; }","duration":"116.398839ms","start":"2026-09-10T01:52:02.148264Z","end":"2026-09-10T01:52:02.264662Z","steps":["trace[740081298] 'agreement among raft nodes before linearized reading' (duration: 116.261896ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:02.627064Z","caller":"traceutil/trace.go:171","msg":"trace[341538795] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"272.494319ms","start":"2026-09-10T01:52:02.354548Z","end":"2026-09-10T01:52:02.627042Z","steps":["trace[341538795] 'read index received' (duration: 272.319146ms)","trace[341538795] 'applied index is now lower than readState.Index' (duration: 174.413µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:02.62712Z","caller":"traceutil/trace.go:171","msg":"trace[2123839386] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"355.124786ms","start":"2026-09-10T01:52:02.271974Z","end":"2026-09-10T01:52:02.627099Z","steps":["trace[2123839386] 'process raft request' (duration: 354.925543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:02.627201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.630111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b904309-2b02-4989-9b66-e00fe3b7a0e5\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-09-10T01:52:02.627238Z","caller":"traceutil/trace.go:171","msg":"trace[1399508577] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b904309-2b02-4989-9b66-e00fe3b7a0e5; range_end:; response_count:1; response_revision:1891; }","duration":"272.718223ms","start":"2026-09-10T01:52:02.354511Z","end":"2026-09-10T01:52:02.627229Z","steps":["trace[1399508577] 'agreement among raft nodes before linearized reading' (duration: 272.637012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:02.627249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:02.271951Z","time spent":"355.219838ms","remote":"127.0.0.1:36390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T01:52:02.784661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.722011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:52:02.784852Z","caller":"traceutil/trace.go:171","msg":"trace[1361876341] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"413.517538ms","start":"2026-09-10T01:52:02.3713Z","end":"2026-09-10T01:52:02.784818Z","steps":["trace[1361876341] 'process raft request' (duration: 310.558523ms)","trace[1361876341] 'compare' (duration: 102.552868ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:02.785017Z","caller":"traceutil/trace.go:171","msg":"trace[787215471] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"157.862569ms","start":"2026-09-10T01:52:02.627127Z","end":"2026-09-10T01:52:02.78499Z","steps":["trace[787215471] 'read index received' (duration: 54.651339ms)","trace[787215471] 'applied index is now lower than readState.Index' (duration: 103.2093ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:02.785048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:02.371282Z","time spent":"413.6733ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T01:52:02.785781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.962281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-10T01:52:02.785841Z","caller":"traceutil/trace.go:171","msg":"trace[1727845663] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1893; }","duration":"386.056182ms","start":"2026-09-10T01:52:02.39977Z","end":"2026-09-10T01:52:02.785826Z","steps":["trace[1727845663] 'agreement among raft nodes before linearized reading' (duration: 385.302648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:02.785871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:02.399747Z","time spent":"386.116653ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-10T01:52:36.259711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.119742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:52:36.259806Z","caller":"traceutil/trace.go:171","msg":"trace[306586126] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2131; }","duration":"129.291474ms","start":"2026-09-10T01:52:36.130495Z","end":"2026-09-10T01:52:36.259787Z","steps":["trace[306586126] 'count revisions from in-memory index tree' (duration: 129.009559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:36.259775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.803908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:52:36.25987Z","caller":"traceutil/trace.go:171","msg":"trace[690967794] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2131; }","duration":"124.954221ms","start":"2026-09-10T01:52:36.134896Z","end":"2026-09-10T01:52:36.25985Z","steps":["trace[690967794] 'count revisions from in-memory index tree' (duration: 124.688077ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:37.08737Z","caller":"traceutil/trace.go:171","msg":"trace[492679507] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"172.125914ms","start":"2026-09-10T01:52:36.91522Z","end":"2026-09-10T01:52:37.087346Z","steps":["trace[492679507] 'process raft request' (duration: 171.932631ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:37.296178Z","caller":"traceutil/trace.go:171","msg":"trace[237507413] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2213; }","duration":"347.191288ms","start":"2026-09-10T01:52:36.948961Z","end":"2026-09-10T01:52:37.296152Z","steps":["trace[237507413] 'read index received' (duration: 138.331074ms)","trace[237507413] 'applied index is now lower than readState.Index' (duration: 208.859134ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:37.296447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.473362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-09-10T01:52:37.296513Z","caller":"traceutil/trace.go:171","msg":"trace[162371933] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2136; }","duration":"347.591023ms","start":"2026-09-10T01:52:36.948911Z","end":"2026-09-10T01:52:37.296502Z","steps":["trace[162371933] 'agreement among raft nodes before linearized reading' (duration: 347.464021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:37.296549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:36.948889Z","time spent":"347.654005ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-09-10T01:52:37.296697Z","caller":"traceutil/trace.go:171","msg":"trace[2000424621] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"374.810207ms","start":"2026-09-10T01:52:36.921877Z","end":"2026-09-10T01:52:37.296687Z","steps":["trace[2000424621] 'process raft request' (duration: 294.500601ms)","trace[2000424621] 'compare' (duration: 79.671456ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:37.296781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:36.921854Z","time spent":"374.882057ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T01:52:37.296794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.92555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-10T01:52:37.296844Z","caller":"traceutil/trace.go:171","msg":"trace[50474656] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2136; }","duration":"226.989131ms","start":"2026-09-10T01:52:37.06984Z","end":"2026-09-10T01:52:37.29683Z","steps":["trace[50474656] 'agreement among raft nodes before linearized reading' (duration: 226.885929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:37.296691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.802947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:118906"} {"level":"warn","ts":"2026-09-10T01:52:37.297024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.990536ms","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-09-10T01:52:37.297137Z","caller":"traceutil/trace.go:171","msg":"trace[1775422207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2136; }","duration":"245.166619ms","start":"2026-09-10T01:52:37.051942Z","end":"2026-09-10T01:52:37.297108Z","steps":["trace[1775422207] 'agreement among raft nodes before linearized reading' (duration: 244.794013ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:37.297072Z","caller":"traceutil/trace.go:171","msg":"trace[1437130689] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2136; }","duration":"344.225433ms","start":"2026-09-10T01:52:36.952839Z","end":"2026-09-10T01:52:37.297064Z","steps":["trace[1437130689] 'agreement among raft nodes before linearized reading' (duration: 343.685975ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:37.298445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:36.952817Z","time spent":"345.616185ms","remote":"127.0.0.1:36280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":118928,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-09-10T01:52:37.297588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.352262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-10T01:52:37.298559Z","caller":"traceutil/trace.go:171","msg":"trace[36719787] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2136; }","duration":"307.355958ms","start":"2026-09-10T01:52:36.991179Z","end":"2026-09-10T01:52:37.298535Z","steps":["trace[36719787] 'agreement among raft nodes before linearized reading' (duration: 306.288652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:37.298617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:36.991166Z","time spent":"307.431109ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-09-10T01:52:37.298234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.725715ms","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-09-10T01:52:37.298722Z","caller":"traceutil/trace.go:171","msg":"trace[2009943273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2136; }","duration":"256.251204ms","start":"2026-09-10T01:52:37.042454Z","end":"2026-09-10T01:52:37.298705Z","steps":["trace[2009943273] 'agreement among raft nodes before linearized reading' (duration: 254.568818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:37.299231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.703839ms","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-09-10T01:52:37.29929Z","caller":"traceutil/trace.go:171","msg":"trace[291220830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2136; }","duration":"239.78406ms","start":"2026-09-10T01:52:37.059491Z","end":"2026-09-10T01:52:37.299275Z","steps":["trace[291220830] 'agreement among raft nodes before linearized reading' (duration: 237.955193ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:37.553424Z","caller":"traceutil/trace.go:171","msg":"trace[1906752191] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"250.166694ms","start":"2026-09-10T01:52:37.303237Z","end":"2026-09-10T01:52:37.553404Z","steps":["trace[1906752191] 'process raft request' (duration: 247.836809ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:37.553429Z","caller":"traceutil/trace.go:171","msg":"trace[1215037777] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2215; }","duration":"249.624337ms","start":"2026-09-10T01:52:37.303777Z","end":"2026-09-10T01:52:37.553401Z","steps":["trace[1215037777] 'read index received' (duration: 247.404434ms)","trace[1215037777] 'applied index is now lower than readState.Index' (duration: 2.218813ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:37.554341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.53316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25778"} {"level":"info","ts":"2026-09-10T01:52:37.554423Z","caller":"traceutil/trace.go:171","msg":"trace[2137710804] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2137; }","duration":"250.648821ms","start":"2026-09-10T01:52:37.30376Z","end":"2026-09-10T01:52:37.554409Z","steps":["trace[2137710804] 'agreement among raft nodes before linearized reading' (duration: 249.712448ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:37.562399Z","caller":"traceutil/trace.go:171","msg":"trace[1621514975] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"258.501777ms","start":"2026-09-10T01:52:37.303876Z","end":"2026-09-10T01:52:37.562378Z","steps":["trace[1621514975] 'process raft request' (duration: 258.309594ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:37.562438Z","caller":"traceutil/trace.go:171","msg":"trace[2123998210] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"256.920573ms","start":"2026-09-10T01:52:37.3055Z","end":"2026-09-10T01:52:37.562421Z","steps":["trace[2123998210] 'process raft request' (duration: 256.838772ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:37.562458Z","caller":"traceutil/trace.go:171","msg":"trace[1915789599] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"256.190973ms","start":"2026-09-10T01:52:37.306247Z","end":"2026-09-10T01:52:37.562438Z","steps":["trace[1915789599] 'process raft request' (duration: 256.114461ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:37.56262Z","caller":"traceutil/trace.go:171","msg":"trace[1770866146] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"257.671904ms","start":"2026-09-10T01:52:37.304937Z","end":"2026-09-10T01:52:37.562609Z","steps":["trace[1770866146] 'process raft request' (duration: 257.37007ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:53:46.700274Z","caller":"traceutil/trace.go:171","msg":"trace[1120567859] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"344.174562ms","start":"2026-09-10T01:53:46.356076Z","end":"2026-09-10T01:53:46.700251Z","steps":["trace[1120567859] 'process raft request' (duration: 344.0328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:53:46.70047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:53:46.356052Z","time spent":"344.298853ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:53:46.703577Z","caller":"traceutil/trace.go:171","msg":"trace[2116850680] transaction","detail":"{read_only:false; response_revision:2614; number_of_response:1; }","duration":"157.156651ms","start":"2026-09-10T01:53:46.5464Z","end":"2026-09-10T01:53:46.703557Z","steps":["trace[2116850680] 'process raft request' (duration: 157.05853ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:54:19.675373Z","caller":"traceutil/trace.go:171","msg":"trace[263089750] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"202.860055ms","start":"2026-09-10T01:54:19.472483Z","end":"2026-09-10T01:54:19.675343Z","steps":["trace[263089750] 'process raft request' (duration: 202.686076ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:54:19.870055Z","caller":"traceutil/trace.go:171","msg":"trace[733229581] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"112.053369ms","start":"2026-09-10T01:54:19.757975Z","end":"2026-09-10T01:54:19.870028Z","steps":["trace[733229581] 'process raft request' (duration: 111.88839ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:54:20.265632Z","caller":"traceutil/trace.go:171","msg":"trace[630574819] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"185.459265ms","start":"2026-09-10T01:54:20.08015Z","end":"2026-09-10T01:54:20.265609Z","steps":["trace[630574819] 'process raft request' (duration: 185.088137ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:54:20.314953Z","caller":"traceutil/trace.go:171","msg":"trace[1847782394] linearizableReadLoop","detail":"{readStateIndex:2902; appliedIndex:2900; }","duration":"232.15785ms","start":"2026-09-10T01:54:20.082754Z","end":"2026-09-10T01:54:20.314911Z","steps":["trace[1847782394] 'read index received' (duration: 182.779142ms)","trace[1847782394] 'applied index is now lower than readState.Index' (duration: 49.377278ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:54:20.315083Z","caller":"traceutil/trace.go:171","msg":"trace[148549869] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"234.706826ms","start":"2026-09-10T01:54:20.080363Z","end":"2026-09-10T01:54:20.31507Z","steps":["trace[148549869] 'process raft request' (duration: 208.216738ms)","trace[148549869] 'compare' (duration: 26.083048ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:54:20.315276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.052044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-10T01:54:20.315354Z","caller":"traceutil/trace.go:171","msg":"trace[1045255055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2799; }","duration":"232.245243ms","start":"2026-09-10T01:54:20.08309Z","end":"2026-09-10T01:54:20.315336Z","steps":["trace[1045255055] 'agreement among raft nodes before linearized reading' (duration: 231.987881ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:54:20.315553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.433577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-10T01:54:20.315603Z","caller":"traceutil/trace.go:171","msg":"trace[1935087748] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2799; }","duration":"218.514061ms","start":"2026-09-10T01:54:20.097073Z","end":"2026-09-10T01:54:20.315587Z","steps":["trace[1935087748] 'agreement among raft nodes before linearized reading' (duration: 218.334602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:54:20.315598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.823474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1313"} {"level":"info","ts":"2026-09-10T01:54:20.31572Z","caller":"traceutil/trace.go:171","msg":"trace[1515516885] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2799; }","duration":"232.96217ms","start":"2026-09-10T01:54:20.08274Z","end":"2026-09-10T01:54:20.315702Z","steps":["trace[1515516885] 'agreement among raft nodes before linearized reading' (duration: 232.619263ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:46.328289Z","caller":"traceutil/trace.go:171","msg":"trace[547185725] linearizableReadLoop","detail":"{readStateIndex:4919; appliedIndex:4918; }","duration":"164.678558ms","start":"2026-09-10T01:58:46.163582Z","end":"2026-09-10T01:58:46.32826Z","steps":["trace[547185725] 'read index received' (duration: 13.500259ms)","trace[547185725] 'applied index is now lower than readState.Index' (duration: 151.177179ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:58:46.32847Z","caller":"traceutil/trace.go:171","msg":"trace[1728601888] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"177.254427ms","start":"2026-09-10T01:58:46.151128Z","end":"2026-09-10T01:58:46.328382Z","steps":["trace[1728601888] 'process raft request' (duration: 174.97228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:58:46.328618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.1399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:58:46.328666Z","caller":"traceutil/trace.go:171","msg":"trace[252207806] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4736; }","duration":"155.206872ms","start":"2026-09-10T01:58:46.173442Z","end":"2026-09-10T01:58:46.328648Z","steps":["trace[252207806] 'agreement among raft nodes before linearized reading' (duration: 155.12181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:58:46.328523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.909603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:58:46.328715Z","caller":"traceutil/trace.go:171","msg":"trace[1117029369] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:0; response_revision:4736; }","duration":"165.159318ms","start":"2026-09-10T01:58:46.163538Z","end":"2026-09-10T01:58:46.328698Z","steps":["trace[1117029369] 'agreement among raft nodes before linearized reading' (duration: 164.915113ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:51.554257Z","caller":"traceutil/trace.go:171","msg":"trace[1036587249] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5080; }","duration":"102.644787ms","start":"2026-09-10T01:58:51.451589Z","end":"2026-09-10T01:58:51.554234Z","steps":["trace[1036587249] 'read index received' (duration: 13.315863ms)","trace[1036587249] 'applied index is now lower than readState.Index' (duration: 89.327784ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:58:51.554418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.80497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T01:58:51.554456Z","caller":"traceutil/trace.go:171","msg":"trace[869769536] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4896; }","duration":"102.901923ms","start":"2026-09-10T01:58:51.451544Z","end":"2026-09-10T01:58:51.554446Z","steps":["trace[869769536] 'agreement among raft nodes before linearized reading' (duration: 102.77956ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:51.554688Z","caller":"traceutil/trace.go:171","msg":"trace[40907073] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"107.055208ms","start":"2026-09-10T01:58:51.447595Z","end":"2026-09-10T01:58:51.55465Z","steps":["trace[40907073] 'process raft request' (duration: 106.486266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:58:58.6577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.172181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T01:58:58.657867Z","caller":"traceutil/trace.go:171","msg":"trace[1106318256] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4971; }","duration":"116.418485ms","start":"2026-09-10T01:58:58.541404Z","end":"2026-09-10T01:58:58.657822Z","steps":["trace[1106318256] 'count revisions from in-memory index tree' (duration: 115.960426ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:59.548004Z","caller":"traceutil/trace.go:171","msg":"trace[1106205333] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"163.307774ms","start":"2026-09-10T01:58:59.384661Z","end":"2026-09-10T01:58:59.547969Z","steps":["trace[1106205333] 'process raft request' (duration: 163.11786ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:59.548108Z","caller":"traceutil/trace.go:171","msg":"trace[653197401] linearizableReadLoop","detail":"{readStateIndex:5168; appliedIndex:5168; }","duration":"162.261333ms","start":"2026-09-10T01:58:59.385814Z","end":"2026-09-10T01:58:59.548075Z","steps":["trace[653197401] 'read index received' (duration: 162.246333ms)","trace[653197401] 'applied index is now lower than readState.Index' (duration: 12.58µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:58:59.54858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.739153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-10T01:58:59.548685Z","caller":"traceutil/trace.go:171","msg":"trace[834759648] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4979; }","duration":"162.917936ms","start":"2026-09-10T01:58:59.385738Z","end":"2026-09-10T01:58:59.548655Z","steps":["trace[834759648] 'agreement among raft nodes before linearized reading' (duration: 162.441267ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:59.569602Z","caller":"traceutil/trace.go:171","msg":"trace[164116689] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"176.546113ms","start":"2026-09-10T01:58:59.393019Z","end":"2026-09-10T01:58:59.569565Z","steps":["trace[164116689] 'process raft request' (duration: 176.120724ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:05.897803Z","caller":"traceutil/trace.go:171","msg":"trace[337249984] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"201.287701ms","start":"2026-09-10T01:59:05.696489Z","end":"2026-09-10T01:59:05.897777Z","steps":["trace[337249984] 'process raft request' (duration: 201.080296ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:05.904994Z","caller":"traceutil/trace.go:171","msg":"trace[1441614895] linearizableReadLoop","detail":"{readStateIndex:5314; appliedIndex:5312; }","duration":"167.005041ms","start":"2026-09-10T01:59:05.737971Z","end":"2026-09-10T01:59:05.904976Z","steps":["trace[1441614895] 'read index received' (duration: 159.612032ms)","trace[1441614895] 'applied index is now lower than readState.Index' (duration: 7.391909ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:59:05.905101Z","caller":"traceutil/trace.go:171","msg":"trace[159727322] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"202.997784ms","start":"2026-09-10T01:59:05.70209Z","end":"2026-09-10T01:59:05.905087Z","steps":["trace[159727322] 'process raft request' (duration: 202.75855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:05.905365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.382858ms","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-09-10T01:59:05.905435Z","caller":"traceutil/trace.go:171","msg":"trace[246298297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5121; }","duration":"167.520531ms","start":"2026-09-10T01:59:05.737897Z","end":"2026-09-10T01:59:05.905418Z","steps":["trace[246298297] 'agreement among raft nodes before linearized reading' (duration: 167.267866ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:08.919081Z","caller":"traceutil/trace.go:171","msg":"trace[397271206] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"144.738961ms","start":"2026-09-10T01:59:08.77432Z","end":"2026-09-10T01:59:08.919059Z","steps":["trace[397271206] 'process raft request' (duration: 144.571617ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:08.919086Z","caller":"traceutil/trace.go:171","msg":"trace[1635836266] linearizableReadLoop","detail":"{readStateIndex:5349; appliedIndex:5346; }","duration":"137.325042ms","start":"2026-09-10T01:59:08.781744Z","end":"2026-09-10T01:59:08.919069Z","steps":["trace[1635836266] 'read index received' (duration: 7.749235ms)","trace[1635836266] 'applied index is now lower than readState.Index' (duration: 129.574117ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:59:08.919264Z","caller":"traceutil/trace.go:171","msg":"trace[1248951953] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"145.050737ms","start":"2026-09-10T01:59:08.774184Z","end":"2026-09-10T01:59:08.919234Z","steps":["trace[1248951953] 'process raft request' (duration: 122.430584ms)","trace[1248951953] 'compare' (duration: 21.996051ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:08.919727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.965134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-10T01:59:08.919794Z","caller":"traceutil/trace.go:171","msg":"trace[1032011085] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5156; }","duration":"138.061257ms","start":"2026-09-10T01:59:08.78171Z","end":"2026-09-10T01:59:08.919771Z","steps":["trace[1032011085] 'agreement among raft nodes before linearized reading' (duration: 137.425244ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:16.971857Z","caller":"traceutil/trace.go:171","msg":"trace[348272142] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"164.969662ms","start":"2026-09-10T01:59:16.806857Z","end":"2026-09-10T01:59:16.971827Z","steps":["trace[348272142] 'process raft request' (duration: 164.762408ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:16.971967Z","caller":"traceutil/trace.go:171","msg":"trace[1908206902] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5227; }","duration":"100.017104ms","start":"2026-09-10T01:59:16.871924Z","end":"2026-09-10T01:59:16.971941Z","steps":["trace[1908206902] 'agreement among raft nodes before linearized reading' (duration: 99.890651ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:35.741946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2879} {"level":"info","ts":"2026-09-10T01:59:35.822669Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2879,"took":"79.275026ms","hash":2952724433,"current-db-size-bytes":51109888,"current-db-size":"51 MB","current-db-size-in-use-bytes":27508736,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-10T01:59:35.822728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2952724433,"revision":2879,"compact-revision":-1} {"level":"info","ts":"2026-09-10T01:59:55.987987Z","caller":"traceutil/trace.go:171","msg":"trace[1631438110] linearizableReadLoop","detail":"{readStateIndex:5775; appliedIndex:5774; }","duration":"161.123956ms","start":"2026-09-10T01:59:55.826841Z","end":"2026-09-10T01:59:55.987965Z","steps":["trace[1631438110] 'read index received' (duration: 160.859582ms)","trace[1631438110] 'applied index is now lower than readState.Index' (duration: 263.674µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:55.988331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.850018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T01:59:55.988382Z","caller":"traceutil/trace.go:171","msg":"trace[880447936] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:5570; }","duration":"154.943539ms","start":"2026-09-10T01:59:55.833426Z","end":"2026-09-10T01:59:55.98837Z","steps":["trace[880447936] 'agreement among raft nodes before linearized reading' (duration: 154.848357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:55.988701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.505249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:59:55.988851Z","caller":"traceutil/trace.go:171","msg":"trace[2040613153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5570; }","duration":"136.750613ms","start":"2026-09-10T01:59:55.852059Z","end":"2026-09-10T01:59:55.98881Z","steps":["trace[2040613153] 'agreement among raft nodes before linearized reading' (duration: 136.462868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:55.988996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.136806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-10T01:59:55.989253Z","caller":"traceutil/trace.go:171","msg":"trace[1186436519] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5570; }","duration":"162.270229ms","start":"2026-09-10T01:59:55.826789Z","end":"2026-09-10T01:59:55.989059Z","steps":["trace[1186436519] 'agreement among raft nodes before linearized reading' (duration: 161.368911ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:55.989386Z","caller":"traceutil/trace.go:171","msg":"trace[1878220584] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"227.780132ms","start":"2026-09-10T01:59:55.76155Z","end":"2026-09-10T01:59:55.98933Z","steps":["trace[1878220584] 'process raft request' (duration: 226.215952ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:56.196439Z","caller":"traceutil/trace.go:171","msg":"trace[1933943274] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"106.645281ms","start":"2026-09-10T01:59:56.08974Z","end":"2026-09-10T01:59:56.196385Z","steps":["trace[1933943274] 'process raft request' (duration: 106.389346ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:56.385053Z","caller":"traceutil/trace.go:171","msg":"trace[510255823] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"183.883195ms","start":"2026-09-10T01:59:56.201126Z","end":"2026-09-10T01:59:56.385009Z","steps":["trace[510255823] 'process raft request' (duration: 155.65067ms)","trace[510255823] 'compare' (duration: 28.019531ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:59:56.411877Z","caller":"traceutil/trace.go:171","msg":"trace[40355523] linearizableReadLoop","detail":"{readStateIndex:5778; appliedIndex:5776; }","duration":"207.140185ms","start":"2026-09-10T01:59:56.204712Z","end":"2026-09-10T01:59:56.411852Z","steps":["trace[40355523] 'read index received' (duration: 152.077402ms)","trace[40355523] 'applied index is now lower than readState.Index' (duration: 55.061583ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:59:56.412144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.405031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-10T01:59:56.412201Z","caller":"traceutil/trace.go:171","msg":"trace[1830144519] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5573; }","duration":"207.488542ms","start":"2026-09-10T01:59:56.204687Z","end":"2026-09-10T01:59:56.412175Z","steps":["trace[1830144519] 'agreement among raft nodes before linearized reading' (duration: 207.287478ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:56.412141Z","caller":"traceutil/trace.go:171","msg":"trace[1558206457] transaction","detail":"{read_only:false; response_revision:5573; number_of_response:1; }","duration":"209.958408ms","start":"2026-09-10T01:59:56.201975Z","end":"2026-09-10T01:59:56.411934Z","steps":["trace[1558206457] 'process raft request' (duration: 209.742624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:56.412257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.796593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-10T01:59:56.412638Z","caller":"traceutil/trace.go:171","msg":"trace[1193506079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5573; }","duration":"204.19416ms","start":"2026-09-10T01:59:56.208414Z","end":"2026-09-10T01:59:56.412608Z","steps":["trace[1193506079] 'agreement among raft nodes before linearized reading' (duration: 203.712141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:56.412856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.152501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-10T01:59:56.412932Z","caller":"traceutil/trace.go:171","msg":"trace[1767955720] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5573; }","duration":"193.300263ms","start":"2026-09-10T01:59:56.219605Z","end":"2026-09-10T01:59:56.412905Z","steps":["trace[1767955720] 'agreement among raft nodes before linearized reading' (duration: 192.955537ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:35.748839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5357} {"level":"info","ts":"2026-09-10T02:04:35.818016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5357,"took":"68.123698ms","hash":3189553426,"current-db-size-bytes":51109888,"current-db-size":"51 MB","current-db-size-in-use-bytes":18567168,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-10T02:04:35.818067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3189553426,"revision":5357,"compact-revision":2879}