{"level":"warn","ts":"2026-04-09T05:59:10.882972Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T05:59:10.883074Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-04-09T05:59:10.883153Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T05:59:10.88317Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-09T05:59:10.883199Z","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-04-09T05:59:10.884174Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-04-09T05:59:10.884277Z","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://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-04-09T05:59:10.888101Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.585282ms"} {"level":"info","ts":"2026-04-09T05:59:10.894726Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-04-09T05:59:10.894866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T05:59:10.894902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-04-09T05:59:10.894928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T05:59:10.894951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-04-09T05:59:10.895011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-04-09T05:59:10.901272Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T05:59:10.902975Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T05:59:10.905233Z","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-04-09T05:59:10.908784Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T05:59:10.909026Z","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-04-09T05:59:10.909156Z","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-04-09T05:59:10.90927Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T05:59:10.909179Z","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-04-09T05:59:10.910356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-04-09T05:59:10.912589Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-09T05:59:10.914036Z","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-04-09T05:59:10.914177Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-09T05:59:10.914216Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-09T05:59:10.91443Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T05:59:10.914468Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T05:59:11.395441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T05:59:11.395507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T05:59:11.395531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-04-09T05:59:11.395553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-04-09T05:59:11.395564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-09T05:59:11.39558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-04-09T05:59:11.395594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-09T05:59:11.397308Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:59:11.398059Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T05:59:11.398057Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:59:11.398095Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:59:11.398426Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T05:59:11.398579Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T05:59:11.400081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T05:59:11.401562Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-04-09T05:59:11.406991Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:59:11.40712Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:59:11.407154Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:59:24.066169Z","caller":"traceutil/trace.go:171","msg":"trace[1748958759] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"274.839452ms","start":"2026-04-09T05:59:23.791284Z","end":"2026-04-09T05:59:24.066124Z","steps":["trace[1748958759] 'process raft request' (duration: 274.658557ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:24.066258Z","caller":"traceutil/trace.go:171","msg":"trace[150642100] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:410; }","duration":"273.038164ms","start":"2026-04-09T05:59:23.793172Z","end":"2026-04-09T05:59:24.06621Z","steps":["trace[150642100] 'read index received' (duration: 273.023463ms)","trace[150642100] 'applied index is now lower than readState.Index' (duration: 12.18µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:59:24.06649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.29428ms","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-04-09T05:59:24.06663Z","caller":"traceutil/trace.go:171","msg":"trace[1367476311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:398; }","duration":"273.506956ms","start":"2026-04-09T05:59:23.793102Z","end":"2026-04-09T05:59:24.066609Z","steps":["trace[1367476311] 'agreement among raft nodes before linearized reading' (duration: 273.2582ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:41.283173Z","caller":"traceutil/trace.go:171","msg":"trace[2106878186] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"124.399047ms","start":"2026-04-09T05:59:41.158747Z","end":"2026-04-09T05:59:41.283146Z","steps":["trace[2106878186] 'process raft request' (duration: 124.229522ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:41.290189Z","caller":"traceutil/trace.go:171","msg":"trace[841249372] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"108.316118ms","start":"2026-04-09T05:59:41.181826Z","end":"2026-04-09T05:59:41.290142Z","steps":["trace[841249372] 'process raft request' (duration: 108.101992ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:41.290651Z","caller":"traceutil/trace.go:171","msg":"trace[1015184620] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"103.57678ms","start":"2026-04-09T05:59:41.18705Z","end":"2026-04-09T05:59:41.290626Z","steps":["trace[1015184620] 'process raft request' (duration: 103.022185ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:41.290868Z","caller":"traceutil/trace.go:171","msg":"trace[779863566] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"104.552676ms","start":"2026-04-09T05:59:41.186297Z","end":"2026-04-09T05:59:41.29085Z","steps":["trace[779863566] 'process raft request' (duration: 103.737234ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:41.793807Z","caller":"traceutil/trace.go:171","msg":"trace[893287759] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"256.888414ms","start":"2026-04-09T05:59:41.536895Z","end":"2026-04-09T05:59:41.793784Z","steps":["trace[893287759] 'process raft request' (duration: 224.41948ms)","trace[893287759] 'compare' (duration: 32.367201ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:41.803445Z","caller":"traceutil/trace.go:171","msg":"trace[731498146] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"256.221985ms","start":"2026-04-09T05:59:41.547199Z","end":"2026-04-09T05:59:41.803421Z","steps":["trace[731498146] 'process raft request' (duration: 256.173804ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:41.80355Z","caller":"traceutil/trace.go:171","msg":"trace[1892425970] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"258.202489ms","start":"2026-04-09T05:59:41.545294Z","end":"2026-04-09T05:59:41.803497Z","steps":["trace[1892425970] 'process raft request' (duration: 257.971033ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:47.091521Z","caller":"traceutil/trace.go:171","msg":"trace[1144636148] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"127.784339ms","start":"2026-04-09T05:59:46.963708Z","end":"2026-04-09T05:59:47.091492Z","steps":["trace[1144636148] 'process raft request' (duration: 127.611405ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:47.223273Z","caller":"traceutil/trace.go:171","msg":"trace[975116593] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"226.778404ms","start":"2026-04-09T05:59:46.996463Z","end":"2026-04-09T05:59:47.223241Z","steps":["trace[975116593] 'process raft request' (duration: 210.063859ms)","trace[975116593] 'compare' (duration: 16.583341ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:47.2279Z","caller":"traceutil/trace.go:171","msg":"trace[278759329] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"120.153561ms","start":"2026-04-09T05:59:47.107712Z","end":"2026-04-09T05:59:47.227866Z","steps":["trace[278759329] 'read index received' (duration: 98.868661ms)","trace[278759329] 'applied index is now lower than readState.Index' (duration: 21.28282ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:47.227963Z","caller":"traceutil/trace.go:171","msg":"trace[1090289800] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"131.211502ms","start":"2026-04-09T05:59:47.096729Z","end":"2026-04-09T05:59:47.227941Z","steps":["trace[1090289800] 'process raft request' (duration: 130.138102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:47.228079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.351107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:59:47.228142Z","caller":"traceutil/trace.go:171","msg":"trace[229527122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"120.457559ms","start":"2026-04-09T05:59:47.10767Z","end":"2026-04-09T05:59:47.228127Z","steps":["trace[229527122] 'agreement among raft nodes before linearized reading' (duration: 120.342946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:48.211148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.70678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:59:48.211283Z","caller":"traceutil/trace.go:171","msg":"trace[1764996481] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"101.868564ms","start":"2026-04-09T05:59:48.109398Z","end":"2026-04-09T05:59:48.211266Z","steps":["trace[1764996481] 'read index received' (duration: 84.352µs)","trace[1764996481] 'applied index is now lower than readState.Index' (duration: 101.782282ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:48.211324Z","caller":"traceutil/trace.go:171","msg":"trace[1498495939] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"349.470174ms","start":"2026-04-09T05:59:47.861818Z","end":"2026-04-09T05:59:48.211288Z","steps":["trace[1498495939] 'process raft request' (duration: 171.880199ms)","trace[1498495939] 'compare' (duration: 176.219677ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:59:48.211497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.101471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:59:48.211478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:47.861788Z","time spent":"349.620089ms","remote":"127.0.0.1:54146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:59:48.211549Z","caller":"traceutil/trace.go:171","msg":"trace[508609606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"102.207773ms","start":"2026-04-09T05:59:48.10933Z","end":"2026-04-09T05:59:48.211538Z","steps":["trace[508609606] 'agreement among raft nodes before linearized reading' (duration: 102.036858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:15.948423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.191765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:00:15.948544Z","caller":"traceutil/trace.go:171","msg":"trace[197105771] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"227.139848ms","start":"2026-04-09T06:00:15.721387Z","end":"2026-04-09T06:00:15.948526Z","steps":["trace[197105771] 'read index received' (duration: 46.761µs)","trace[197105771] 'applied index is now lower than readState.Index' (duration: 227.091657ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:15.94864Z","caller":"traceutil/trace.go:171","msg":"trace[1051245051] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"304.669549ms","start":"2026-04-09T06:00:15.643959Z","end":"2026-04-09T06:00:15.948629Z","steps":["trace[1051245051] 'process raft request' (duration: 37.001881ms)","trace[1051245051] 'compare' (duration: 267.054541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:00:15.948712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:00:15.643937Z","time spent":"304.742141ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:00:15.948873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.468827ms","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-04-09T06:00:15.948966Z","caller":"traceutil/trace.go:171","msg":"trace[609143882] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"227.627121ms","start":"2026-04-09T06:00:15.72132Z","end":"2026-04-09T06:00:15.948947Z","steps":["trace[609143882] 'agreement among raft nodes before linearized reading' (duration: 227.332373ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:27.016462Z","caller":"traceutil/trace.go:171","msg":"trace[406559954] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"129.321933ms","start":"2026-04-09T06:01:26.887112Z","end":"2026-04-09T06:01:27.016434Z","steps":["trace[406559954] 'process raft request' (duration: 129.185409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:27.271499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.618412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-09T06:01:27.271596Z","caller":"traceutil/trace.go:171","msg":"trace[1514699806] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1874; }","duration":"206.761186ms","start":"2026-04-09T06:01:27.064812Z","end":"2026-04-09T06:01:27.271574Z","steps":["trace[1514699806] 'range keys from in-memory index tree' (duration: 206.426347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:27.271504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.206776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:01:27.271689Z","caller":"traceutil/trace.go:171","msg":"trace[773451598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1874; }","duration":"163.414382ms","start":"2026-04-09T06:01:27.108259Z","end":"2026-04-09T06:01:27.271674Z","steps":["trace[773451598] 'range keys from in-memory index tree' (duration: 162.830795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:27.69473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.613233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:01:27.694841Z","caller":"traceutil/trace.go:171","msg":"trace[1841894485] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1875; }","duration":"133.781557ms","start":"2026-04-09T06:01:27.561036Z","end":"2026-04-09T06:01:27.694818Z","steps":["trace[1841894485] 'count revisions from in-memory index tree' (duration: 133.495299ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:40.117488Z","caller":"traceutil/trace.go:171","msg":"trace[891474967] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"101.863357ms","start":"2026-04-09T06:01:40.015585Z","end":"2026-04-09T06:01:40.117449Z","steps":["trace[891474967] 'process raft request' (duration: 89.886677ms)","trace[891474967] 'compare' (duration: 11.724643ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:02:02.518906Z","caller":"traceutil/trace.go:171","msg":"trace[1243227152] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"103.309653ms","start":"2026-04-09T06:02:02.41556Z","end":"2026-04-09T06:02:02.51887Z","steps":["trace[1243227152] 'process raft request' (duration: 18.322046ms)","trace[1243227152] 'compare' (duration: 84.787292ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:02:23.507584Z","caller":"traceutil/trace.go:171","msg":"trace[1038071339] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"166.500111ms","start":"2026-04-09T06:02:23.341038Z","end":"2026-04-09T06:02:23.507538Z","steps":["trace[1038071339] 'process raft request' (duration: 78.226135ms)","trace[1038071339] 'compare' (duration: 88.131113ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:03:39.795061Z","caller":"traceutil/trace.go:171","msg":"trace[323955122] linearizableReadLoop","detail":"{readStateIndex:2937; appliedIndex:2936; }","duration":"177.707089ms","start":"2026-04-09T06:03:39.617323Z","end":"2026-04-09T06:03:39.79503Z","steps":["trace[323955122] 'read index received' (duration: 177.538144ms)","trace[323955122] 'applied index is now lower than readState.Index' (duration: 167.655µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:03:39.795185Z","caller":"traceutil/trace.go:171","msg":"trace[978881633] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"180.977018ms","start":"2026-04-09T06:03:39.614173Z","end":"2026-04-09T06:03:39.79515Z","steps":["trace[978881633] 'process raft request' (duration: 180.613219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:03:39.795341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.002188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"info","ts":"2026-04-09T06:03:39.795397Z","caller":"traceutil/trace.go:171","msg":"trace[29501675] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2834; }","duration":"178.09616ms","start":"2026-04-09T06:03:39.617289Z","end":"2026-04-09T06:03:39.795385Z","steps":["trace[29501675] 'agreement among raft nodes before linearized reading' (duration: 177.885654ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:06:01.315611Z","caller":"traceutil/trace.go:171","msg":"trace[196500145] transaction","detail":"{read_only:false; response_revision:3780; number_of_response:1; }","duration":"115.60623ms","start":"2026-04-09T06:06:01.199954Z","end":"2026-04-09T06:06:01.31556Z","steps":["trace[196500145] 'process raft request' (duration: 109.961315ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:38.306002Z","caller":"traceutil/trace.go:171","msg":"trace[764965726] linearizableReadLoop","detail":"{readStateIndex:5294; appliedIndex:5293; }","duration":"197.810091ms","start":"2026-04-09T06:07:38.108167Z","end":"2026-04-09T06:07:38.305977Z","steps":["trace[764965726] 'read index received' (duration: 197.627256ms)","trace[764965726] 'applied index is now lower than readState.Index' (duration: 181.805µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:38.306066Z","caller":"traceutil/trace.go:171","msg":"trace[1099764817] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"234.707783ms","start":"2026-04-09T06:07:38.071316Z","end":"2026-04-09T06:07:38.306024Z","steps":["trace[1099764817] 'process raft request' (duration: 234.470027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:38.306302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.114889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:07:38.306401Z","caller":"traceutil/trace.go:171","msg":"trace[272354044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5106; }","duration":"198.256453ms","start":"2026-04-09T06:07:38.108127Z","end":"2026-04-09T06:07:38.306383Z","steps":["trace[272354044] 'agreement among raft nodes before linearized reading' (duration: 197.987936ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:38.720181Z","caller":"traceutil/trace.go:171","msg":"trace[368989166] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"118.414982ms","start":"2026-04-09T06:07:38.601741Z","end":"2026-04-09T06:07:38.720156Z","steps":["trace[368989166] 'process raft request' (duration: 118.156844ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:38.753254Z","caller":"traceutil/trace.go:171","msg":"trace[1698364914] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"143.993682ms","start":"2026-04-09T06:07:38.609242Z","end":"2026-04-09T06:07:38.753236Z","steps":["trace[1698364914] 'process raft request' (duration: 143.829918ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:38.870832Z","caller":"traceutil/trace.go:171","msg":"trace[755937684] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"112.358555ms","start":"2026-04-09T06:07:38.758444Z","end":"2026-04-09T06:07:38.870802Z","steps":["trace[755937684] 'process raft request' (duration: 108.134889ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:38.882873Z","caller":"traceutil/trace.go:171","msg":"trace[475534175] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"123.003457ms","start":"2026-04-09T06:07:38.759834Z","end":"2026-04-09T06:07:38.882838Z","steps":["trace[475534175] 'process raft request' (duration: 122.75796ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:38.883188Z","caller":"traceutil/trace.go:171","msg":"trace[1195543719] linearizableReadLoop","detail":"{readStateIndex:5304; appliedIndex:5302; }","duration":"123.142191ms","start":"2026-04-09T06:07:38.760022Z","end":"2026-04-09T06:07:38.883164Z","steps":["trace[1195543719] 'read index received' (duration: 106.639457ms)","trace[1195543719] 'applied index is now lower than readState.Index' (duration: 16.501514ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:38.883288Z","caller":"traceutil/trace.go:171","msg":"trace[1184919862] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"122.589056ms","start":"2026-04-09T06:07:38.760681Z","end":"2026-04-09T06:07:38.88327Z","steps":["trace[1184919862] 'process raft request' (duration: 122.103262ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:38.88334Z","caller":"traceutil/trace.go:171","msg":"trace[359237929] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"120.319333ms","start":"2026-04-09T06:07:38.762982Z","end":"2026-04-09T06:07:38.883301Z","steps":["trace[359237929] 'process raft request' (duration: 120.071867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:38.883457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.136313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-69a02bd0-78b4-4d5a-b715-cd4400d09cee\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-09T06:07:38.883505Z","caller":"traceutil/trace.go:171","msg":"trace[1289901447] range","detail":"{range_begin:/registry/persistentvolumes/pvc-69a02bd0-78b4-4d5a-b715-cd4400d09cee; range_end:; response_count:1; response_revision:5118; }","duration":"118.229315ms","start":"2026-04-09T06:07:38.765261Z","end":"2026-04-09T06:07:38.883491Z","steps":["trace[1289901447] 'agreement among raft nodes before linearized reading' (duration: 118.057481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:38.883502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.708214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62911"} {"level":"info","ts":"2026-04-09T06:07:38.883574Z","caller":"traceutil/trace.go:171","msg":"trace[1599972987] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5118; }","duration":"124.803166ms","start":"2026-04-09T06:07:38.758754Z","end":"2026-04-09T06:07:38.883557Z","steps":["trace[1599972987] 'agreement among raft nodes before linearized reading' (duration: 124.547999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:45.441832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495257009839436,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T06:07:45.441876Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.437874092s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T06:07:45.471264Z","caller":"traceutil/trace.go:171","msg":"trace[388966283] linearizableReadLoop","detail":"{readStateIndex:5401; appliedIndex:5399; }","duration":"1.363231303s","start":"2026-04-09T06:07:44.108022Z","end":"2026-04-09T06:07:45.471253Z","steps":["trace[388966283] 'read index received' (duration: 1.334531045s)","trace[388966283] 'applied index is now lower than readState.Index' (duration: 28.699488ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:45.47142Z","caller":"traceutil/trace.go:171","msg":"trace[19835914] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"1.467482865s","start":"2026-04-09T06:07:44.003928Z","end":"2026-04-09T06:07:45.471411Z","steps":["trace[19835914] 'process raft request' (duration: 1.438037656s)","trace[19835914] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:415; } (duration: 29.174481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:07:45.471487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:44.003917Z","time spent":"1.467517696s","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:07:45.471655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363636645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:07:45.471672Z","caller":"traceutil/trace.go:171","msg":"trace[2025248843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5211; }","duration":"1.363685656s","start":"2026-04-09T06:07:44.107981Z","end":"2026-04-09T06:07:45.471667Z","steps":["trace[2025248843] 'agreement among raft nodes before linearized reading' (duration: 1.363660135s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:45.471684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:44.107963Z","time spent":"1.363718936s","remote":"127.0.0.1:53616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T06:07:46.218562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.644887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:07:46.218684Z","caller":"traceutil/trace.go:171","msg":"trace[1624565729] linearizableReadLoop","detail":"{readStateIndex:5402; appliedIndex:5401; }","duration":"747.368567ms","start":"2026-04-09T06:07:45.471295Z","end":"2026-04-09T06:07:46.218663Z","steps":["trace[1624565729] 'read index received' (duration: 275.482733ms)","trace[1624565729] 'applied index is now lower than readState.Index' (duration: 471.883394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:07:46.218861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.738226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:07:46.218928Z","caller":"traceutil/trace.go:171","msg":"trace[716620214] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5212; }","duration":"747.835459ms","start":"2026-04-09T06:07:45.471075Z","end":"2026-04-09T06:07:46.218911Z","steps":["trace[716620214] 'agreement among raft nodes before linearized reading' (duration: 747.666955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:46.218929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.82783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-09T06:07:46.218953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.471061Z","time spent":"747.88557ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-04-09T06:07:46.218996Z","caller":"traceutil/trace.go:171","msg":"trace[1071902477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5212; }","duration":"747.875511ms","start":"2026-04-09T06:07:45.471088Z","end":"2026-04-09T06:07:46.218964Z","steps":["trace[1071902477] 'agreement among raft nodes before linearized reading' (duration: 747.657135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:46.219041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.471071Z","time spent":"747.958613ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T06:07:46.219232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.692569ms","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-04-09T06:07:46.219232Z","caller":"traceutil/trace.go:171","msg":"trace[1766516537] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"748.030635ms","start":"2026-04-09T06:07:45.47111Z","end":"2026-04-09T06:07:46.21914Z","steps":["trace[1766516537] 'process raft request' (duration: 275.710109ms)","trace[1766516537] 'compare' (duration: 471.185605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:07:46.219267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.419555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T06:07:46.219283Z","caller":"traceutil/trace.go:171","msg":"trace[1897240405] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5212; }","duration":"777.76585ms","start":"2026-04-09T06:07:45.441504Z","end":"2026-04-09T06:07:46.21927Z","steps":["trace[1897240405] 'agreement among raft nodes before linearized reading' (duration: 777.619416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:46.219292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.161158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-09T06:07:46.219307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.398205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-24bkn\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T06:07:46.219332Z","caller":"traceutil/trace.go:171","msg":"trace[1966869424] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:5212; }","duration":"748.513997ms","start":"2026-04-09T06:07:45.470801Z","end":"2026-04-09T06:07:46.219315Z","steps":["trace[1966869424] 'agreement among raft nodes before linearized reading' (duration: 748.313632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:46.219365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.471102Z","time spent":"748.202149ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:07:46.219331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.441494Z","time spent":"777.828043ms","remote":"127.0.0.1:53882","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":"info","ts":"2026-04-09T06:07:46.21938Z","caller":"traceutil/trace.go:171","msg":"trace[2097606715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5212; }","duration":"748.266361ms","start":"2026-04-09T06:07:45.471093Z","end":"2026-04-09T06:07:46.219359Z","steps":["trace[2097606715] 'agreement among raft nodes before linearized reading' (duration: 748.011854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:46.219389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.47079Z","time spent":"748.587731ms","remote":"127.0.0.1:53674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"info","ts":"2026-04-09T06:07:46.219389Z","caller":"traceutil/trace.go:171","msg":"trace[633993518] range","detail":"{range_begin:/registry/pods/openstack/keepalived-24bkn; range_end:; response_count:1; response_revision:5212; }","duration":"748.503139ms","start":"2026-04-09T06:07:45.470867Z","end":"2026-04-09T06:07:46.21937Z","steps":["trace[633993518] 'agreement among raft nodes before linearized reading' (duration: 748.261391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:46.219325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.115186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-09T06:07:46.21943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.471089Z","time spent":"748.331334ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T06:07:46.219443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.470857Z","time spent":"748.57397ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-24bkn\" "} {"level":"info","ts":"2026-04-09T06:07:46.219467Z","caller":"traceutil/trace.go:171","msg":"trace[2064505241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5212; }","duration":"748.299403ms","start":"2026-04-09T06:07:45.471142Z","end":"2026-04-09T06:07:46.219441Z","steps":["trace[2064505241] 'agreement among raft nodes before linearized reading' (duration: 747.939632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:46.219523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.471126Z","time spent":"748.384076ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T06:07:46.719697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495257009839442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T06:07:47.22072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495257009839442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T06:07:47.363897Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.519923454s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-09T06:07:47.371646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.745096ms","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-04-09T06:07:47.371867Z","caller":"traceutil/trace.go:171","msg":"trace[933556441] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5212; }","duration":"771.958802ms","start":"2026-04-09T06:07:46.599857Z","end":"2026-04-09T06:07:47.371815Z","steps":["trace[933556441] 'range keys from in-memory index tree' (duration: 771.713765ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:47.372671Z","caller":"traceutil/trace.go:171","msg":"trace[269768277] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"1.528771467s","start":"2026-04-09T06:07:45.843871Z","end":"2026-04-09T06:07:47.372642Z","steps":["trace[269768277] 'process raft request' (duration: 1.520215252s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.372875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.843847Z","time spent":"1.528913791s","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:07:47.375322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:46.22017Z","time spent":"1.155148191s","remote":"127.0.0.1:53674","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-09T06:07:47.375572Z","caller":"traceutil/trace.go:171","msg":"trace[246650973] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"1.152275711s","start":"2026-04-09T06:07:46.223277Z","end":"2026-04-09T06:07:47.375553Z","steps":["trace[246650973] 'process raft request' (duration: 1.152147238s)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:47.375588Z","caller":"traceutil/trace.go:171","msg":"trace[929439876] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"1.152489978s","start":"2026-04-09T06:07:46.223015Z","end":"2026-04-09T06:07:47.375505Z","steps":["trace[929439876] 'process raft request' (duration: 1.15220832s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.375706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:46.223269Z","time spent":"1.152371954s","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:07:47.375756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:46.223003Z","time spent":"1.152650472s","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:07:47.375925Z","caller":"traceutil/trace.go:171","msg":"trace[2122022760] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"1.151958732s","start":"2026-04-09T06:07:46.223941Z","end":"2026-04-09T06:07:47.375899Z","steps":["trace[2122022760] 'process raft request' (duration: 1.151559462s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.376006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:46.223934Z","time spent":"1.152027845s","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:07:47.376018Z","caller":"traceutil/trace.go:171","msg":"trace[1176436062] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"984.648279ms","start":"2026-04-09T06:07:46.39136Z","end":"2026-04-09T06:07:47.376008Z","steps":["trace[1176436062] 'process raft request' (duration: 984.572457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.376063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:46.391343Z","time spent":"984.69711ms","remote":"127.0.0.1:53798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18274,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:07:47.376113Z","caller":"traceutil/trace.go:171","msg":"trace[201557731] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"1.152051596s","start":"2026-04-09T06:07:46.224046Z","end":"2026-04-09T06:07:47.376098Z","steps":["trace[201557731] 'process raft request' (duration: 1.151773048s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.376196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:46.224024Z","time spent":"1.152129687s","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:07:47.376565Z","caller":"traceutil/trace.go:171","msg":"trace[264389434] linearizableReadLoop","detail":"{readStateIndex:5410; appliedIndex:5402; }","duration":"1.157775833s","start":"2026-04-09T06:07:46.218715Z","end":"2026-04-09T06:07:47.376491Z","steps":["trace[264389434] 'read index received' (duration: 1.145457895s)","trace[264389434] 'applied index is now lower than readState.Index' (duration: 12.316158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:07:47.376702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.628072044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-09T06:07:47.376752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.629459251s","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-04-09T06:07:47.376757Z","caller":"traceutil/trace.go:171","msg":"trace[1044122402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5219; }","duration":"1.628164996s","start":"2026-04-09T06:07:45.748577Z","end":"2026-04-09T06:07:47.376742Z","steps":["trace[1044122402] 'agreement among raft nodes before linearized reading' (duration: 1.62792424s)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:47.376529Z","caller":"traceutil/trace.go:171","msg":"trace[646572461] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"290.936007ms","start":"2026-04-09T06:07:47.085568Z","end":"2026-04-09T06:07:47.376504Z","steps":["trace[646572461] 'process raft request' (duration: 290.410302ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:47.376814Z","caller":"traceutil/trace.go:171","msg":"trace[1555329453] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5219; }","duration":"1.629542734s","start":"2026-04-09T06:07:45.747253Z","end":"2026-04-09T06:07:47.376796Z","steps":["trace[1555329453] 'agreement among raft nodes before linearized reading' (duration: 1.62942534s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.376888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.748564Z","time spent":"1.628296269s","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T06:07:47.3769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613601725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xsthn.18a49b3ee32257ec\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-09T06:07:47.37698Z","caller":"traceutil/trace.go:171","msg":"trace[2071622316] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xsthn.18a49b3ee32257ec; range_end:; response_count:1; response_revision:5219; }","duration":"1.613795161s","start":"2026-04-09T06:07:45.763149Z","end":"2026-04-09T06:07:47.376944Z","steps":["trace[2071622316] 'agreement among raft nodes before linearized reading' (duration: 1.61339107s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.376822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.904113191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T06:07:47.376985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.74723Z","time spent":"1.629682168s","remote":"127.0.0.1:53862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-09T06:07:47.377043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.763133Z","time spent":"1.613882714s","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":929,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xsthn.18a49b3ee32257ec\" "} {"level":"info","ts":"2026-04-09T06:07:47.377161Z","caller":"traceutil/trace.go:171","msg":"trace[483032970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5219; }","duration":"1.90446886s","start":"2026-04-09T06:07:45.472612Z","end":"2026-04-09T06:07:47.377081Z","steps":["trace[483032970] 'agreement among raft nodes before linearized reading' (duration: 1.90408801s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.377236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:45.472607Z","time spent":"1.904617414s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T06:07:47.382057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.616179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T06:07:47.382112Z","caller":"traceutil/trace.go:171","msg":"trace[1934717809] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5220; }","duration":"721.733273ms","start":"2026-04-09T06:07:46.660367Z","end":"2026-04-09T06:07:47.3821Z","steps":["trace[1934717809] 'agreement among raft nodes before linearized reading' (duration: 721.60543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.382102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.776273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T06:07:47.382135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:46.660344Z","time spent":"721.785304ms","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-09T06:07:47.382154Z","caller":"traceutil/trace.go:171","msg":"trace[497821016] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5220; }","duration":"757.897025ms","start":"2026-04-09T06:07:46.624244Z","end":"2026-04-09T06:07:47.382141Z","steps":["trace[497821016] 'agreement among raft nodes before linearized reading' (duration: 757.786122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.382198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:46.624215Z","time spent":"757.975858ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-09T06:07:47.382153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.664001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-09T06:07:47.382267Z","caller":"traceutil/trace.go:171","msg":"trace[67567434] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5220; }","duration":"360.810325ms","start":"2026-04-09T06:07:47.021436Z","end":"2026-04-09T06:07:47.382246Z","steps":["trace[67567434] 'agreement among raft nodes before linearized reading' (duration: 360.61885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.382322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:47.021414Z","time spent":"360.889397ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-09T06:07:47.382665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15911931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70968"} {"level":"info","ts":"2026-04-09T06:07:47.382741Z","caller":"traceutil/trace.go:171","msg":"trace[550696808] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5220; }","duration":"1.159211972s","start":"2026-04-09T06:07:46.223518Z","end":"2026-04-09T06:07:47.38273Z","steps":["trace[550696808] 'agreement among raft nodes before linearized reading' (duration: 1.158442471s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:47.382764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:46.22351Z","time spent":"1.159247973s","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":8,"response size":70991,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-09T06:07:53.9528Z","caller":"traceutil/trace.go:171","msg":"trace[1144048844] linearizableReadLoop","detail":"{readStateIndex:5532; appliedIndex:5531; }","duration":"161.466913ms","start":"2026-04-09T06:07:53.791308Z","end":"2026-04-09T06:07:53.952775Z","steps":["trace[1144048844] 'read index received' (duration: 161.263587ms)","trace[1144048844] 'applied index is now lower than readState.Index' (duration: 202.256µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:53.95289Z","caller":"traceutil/trace.go:171","msg":"trace[277134999] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"166.702426ms","start":"2026-04-09T06:07:53.786162Z","end":"2026-04-09T06:07:53.952865Z","steps":["trace[277134999] 'process raft request' (duration: 166.423648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:53.953238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.233191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-633e9f78-55dc-4754-bf3c-fea8c07ba260\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-09T06:07:53.953316Z","caller":"traceutil/trace.go:171","msg":"trace[1041987160] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-633e9f78-55dc-4754-bf3c-fea8c07ba260; range_end:; response_count:1; response_revision:5340; }","duration":"100.348925ms","start":"2026-04-09T06:07:53.852949Z","end":"2026-04-09T06:07:53.953298Z","steps":["trace[1041987160] 'agreement among raft nodes before linearized reading' (duration: 100.135539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:53.953331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.967386ms","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-04-09T06:07:53.953688Z","caller":"traceutil/trace.go:171","msg":"trace[1497061274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5340; }","duration":"162.395018ms","start":"2026-04-09T06:07:53.791254Z","end":"2026-04-09T06:07:53.953649Z","steps":["trace[1497061274] 'agreement among raft nodes before linearized reading' (duration: 161.777192ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:08:07.178776Z","caller":"traceutil/trace.go:171","msg":"trace[1083462024] linearizableReadLoop","detail":"{readStateIndex:5724; appliedIndex:5723; }","duration":"139.223291ms","start":"2026-04-09T06:08:07.039529Z","end":"2026-04-09T06:08:07.178752Z","steps":["trace[1083462024] 'read index received' (duration: 139.041626ms)","trace[1083462024] 'applied index is now lower than readState.Index' (duration: 180.855µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:08:07.178841Z","caller":"traceutil/trace.go:171","msg":"trace[1892154765] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"180.969287ms","start":"2026-04-09T06:08:06.997836Z","end":"2026-04-09T06:08:07.178805Z","steps":["trace[1892154765] 'process raft request' (duration: 180.782922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:08:07.179086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.52422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-09T06:08:07.179201Z","caller":"traceutil/trace.go:171","msg":"trace[411161000] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5525; }","duration":"139.686284ms","start":"2026-04-09T06:08:07.039486Z","end":"2026-04-09T06:08:07.179173Z","steps":["trace[411161000] 'agreement among raft nodes before linearized reading' (duration: 139.372536ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:08:07.291175Z","caller":"traceutil/trace.go:171","msg":"trace[2105622631] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"104.071897ms","start":"2026-04-09T06:08:07.187074Z","end":"2026-04-09T06:08:07.291146Z","steps":["trace[2105622631] 'process raft request' (duration: 69.476738ms)","trace[2105622631] 'compare' (duration: 34.405384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:08:07.291814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.174645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-04-09T06:08:07.291528Z","caller":"traceutil/trace.go:171","msg":"trace[1106556500] transaction","detail":"{read_only:false; response_revision:5528; number_of_response:1; }","duration":"104.401796ms","start":"2026-04-09T06:08:07.187094Z","end":"2026-04-09T06:08:07.291496Z","steps":["trace[1106556500] 'process raft request' (duration: 104.007085ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:08:07.292037Z","caller":"traceutil/trace.go:171","msg":"trace[2012224887] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:5529; }","duration":"102.455052ms","start":"2026-04-09T06:08:07.189562Z","end":"2026-04-09T06:08:07.292017Z","steps":["trace[2012224887] 'agreement among raft nodes before linearized reading' (duration: 102.063702ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:08:07.291585Z","caller":"traceutil/trace.go:171","msg":"trace[628427057] linearizableReadLoop","detail":"{readStateIndex:5728; appliedIndex:5724; }","duration":"101.940188ms","start":"2026-04-09T06:08:07.189599Z","end":"2026-04-09T06:08:07.291539Z","steps":["trace[628427057] 'read index received' (duration: 27.913476ms)","trace[628427057] 'applied index is now lower than readState.Index' (duration: 74.024792ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:08:07.291721Z","caller":"traceutil/trace.go:171","msg":"trace[670967586] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"103.363248ms","start":"2026-04-09T06:08:07.188329Z","end":"2026-04-09T06:08:07.291693Z","steps":["trace[670967586] 'process raft request' (duration: 103.08896ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:09:11.437301Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3010} {"level":"info","ts":"2026-04-09T06:09:11.516131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3010,"took":"77.396957ms","hash":267716889,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":31535104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-09T06:09:11.516208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":267716889,"revision":3010,"compact-revision":-1} {"level":"info","ts":"2026-04-09T06:13:50.055455Z","caller":"traceutil/trace.go:171","msg":"trace[680951667] transaction","detail":"{read_only:false; response_revision:8170; number_of_response:1; }","duration":"140.633672ms","start":"2026-04-09T06:13:49.914789Z","end":"2026-04-09T06:13:50.055423Z","steps":["trace[680951667] 'process raft request' (duration: 140.453548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:01.535491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.423693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-09T06:14:01.535558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.883745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-09T06:14:01.535589Z","caller":"traceutil/trace.go:171","msg":"trace[251087098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8227; }","duration":"114.558356ms","start":"2026-04-09T06:14:01.42101Z","end":"2026-04-09T06:14:01.535568Z","steps":["trace[251087098] 'range keys from in-memory index tree' (duration: 114.271939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:01.535579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.952209ms","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-04-09T06:14:01.535635Z","caller":"traceutil/trace.go:171","msg":"trace[936959219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8227; }","duration":"115.014758ms","start":"2026-04-09T06:14:01.420604Z","end":"2026-04-09T06:14:01.535619Z","steps":["trace[936959219] 'range keys from in-memory index tree' (duration: 114.69698ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:01.535679Z","caller":"traceutil/trace.go:171","msg":"trace[1648114443] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8227; }","duration":"122.122733ms","start":"2026-04-09T06:14:01.413532Z","end":"2026-04-09T06:14:01.535655Z","steps":["trace[1648114443] 'range keys from in-memory index tree' (duration: 121.710132ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:01.72169Z","caller":"traceutil/trace.go:171","msg":"trace[726761958] transaction","detail":"{read_only:false; response_revision:8230; number_of_response:1; }","duration":"181.444408ms","start":"2026-04-09T06:14:01.540217Z","end":"2026-04-09T06:14:01.721661Z","steps":["trace[726761958] 'process raft request' (duration: 100.79916ms)","trace[726761958] 'compare' (duration: 80.450953ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:01.721856Z","caller":"traceutil/trace.go:171","msg":"trace[1451363421] transaction","detail":"{read_only:false; response_revision:8231; number_of_response:1; }","duration":"181.427977ms","start":"2026-04-09T06:14:01.540396Z","end":"2026-04-09T06:14:01.721824Z","steps":["trace[1451363421] 'process raft request' (duration: 181.218492ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:11.444286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6091} {"level":"info","ts":"2026-04-09T06:14:11.565593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6091,"took":"119.238024ms","hash":148254108,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":23150592,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-09T06:14:11.565663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":148254108,"revision":6091,"compact-revision":3010} {"level":"info","ts":"2026-04-09T06:14:31.208192Z","caller":"traceutil/trace.go:171","msg":"trace[1469817813] linearizableReadLoop","detail":"{readStateIndex:8737; appliedIndex:8736; }","duration":"191.827431ms","start":"2026-04-09T06:14:31.016332Z","end":"2026-04-09T06:14:31.20816Z","steps":["trace[1469817813] 'read index received' (duration: 175.134165ms)","trace[1469817813] 'applied index is now lower than readState.Index' (duration: 16.691956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:14:31.208345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.994946ms","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-04-09T06:14:31.208383Z","caller":"traceutil/trace.go:171","msg":"trace[224708737] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8441; }","duration":"192.088709ms","start":"2026-04-09T06:14:31.016284Z","end":"2026-04-09T06:14:31.208373Z","steps":["trace[224708737] 'agreement among raft nodes before linearized reading' (duration: 191.985316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:31.208435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.695216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:14:31.208509Z","caller":"traceutil/trace.go:171","msg":"trace[1729773821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8441; }","duration":"100.795959ms","start":"2026-04-09T06:14:31.107691Z","end":"2026-04-09T06:14:31.208487Z","steps":["trace[1729773821] 'agreement among raft nodes before linearized reading' (duration: 100.669956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:31.2085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.340215ms","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-04-09T06:14:31.209065Z","caller":"traceutil/trace.go:171","msg":"trace[651061094] 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:8441; }","duration":"105.480968ms","start":"2026-04-09T06:14:31.103087Z","end":"2026-04-09T06:14:31.208568Z","steps":["trace[651061094] 'agreement among raft nodes before linearized reading' (duration: 105.254822ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:43.188143Z","caller":"traceutil/trace.go:171","msg":"trace[1175186489] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"162.396716ms","start":"2026-04-09T06:14:43.025722Z","end":"2026-04-09T06:14:43.188118Z","steps":["trace[1175186489] 'process raft request' (duration: 161.858921ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:43.188221Z","caller":"traceutil/trace.go:171","msg":"trace[1922828650] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"178.751004ms","start":"2026-04-09T06:14:43.009437Z","end":"2026-04-09T06:14:43.188188Z","steps":["trace[1922828650] 'process raft request' (duration: 163.98319ms)","trace[1922828650] 'compare' (duration: 14.015483ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:43.653486Z","caller":"traceutil/trace.go:171","msg":"trace[1219512448] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"144.68083ms","start":"2026-04-09T06:14:43.508779Z","end":"2026-04-09T06:14:43.65346Z","steps":["trace[1219512448] 'process raft request' (duration: 69.879923ms)","trace[1219512448] 'compare' (duration: 74.489128ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:43.934779Z","caller":"traceutil/trace.go:171","msg":"trace[1088496753] linearizableReadLoop","detail":"{readStateIndex:8856; appliedIndex:8855; }","duration":"133.637529ms","start":"2026-04-09T06:14:43.801116Z","end":"2026-04-09T06:14:43.934753Z","steps":["trace[1088496753] 'read index received' (duration: 126.143354ms)","trace[1088496753] 'applied index is now lower than readState.Index' (duration: 7.492935ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:43.934825Z","caller":"traceutil/trace.go:171","msg":"trace[728978882] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"145.581455ms","start":"2026-04-09T06:14:43.789209Z","end":"2026-04-09T06:14:43.93479Z","steps":["trace[728978882] 'process raft request' (duration: 138.054479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:43.935024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.885206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T06:14:43.935066Z","caller":"traceutil/trace.go:171","msg":"trace[7376396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8555; }","duration":"133.969407ms","start":"2026-04-09T06:14:43.801087Z","end":"2026-04-09T06:14:43.935057Z","steps":["trace[7376396] 'agreement among raft nodes before linearized reading' (duration: 133.808113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:43.935097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.066725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-24bkn\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T06:14:43.93517Z","caller":"traceutil/trace.go:171","msg":"trace[655027302] range","detail":"{range_begin:/registry/pods/openstack/keepalived-24bkn; range_end:; response_count:1; response_revision:8555; }","duration":"102.149117ms","start":"2026-04-09T06:14:43.833004Z","end":"2026-04-09T06:14:43.935153Z","steps":["trace[655027302] 'agreement among raft nodes before linearized reading' (duration: 101.955102ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:15:35.20153Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b1.wal"} {"level":"info","ts":"2026-04-09T06:16:48.774049Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T06:16:48.780012Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T06:16:48.780105Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T06:19:11.451633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8288} {"level":"info","ts":"2026-04-09T06:19:11.527821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8288,"took":"74.326379ms","hash":2653142512,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":24784896,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-09T06:19:11.527876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2653142512,"revision":8288,"compact-revision":6091} {"level":"info","ts":"2026-04-09T06:24:11.456867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10680} {"level":"info","ts":"2026-04-09T06:24:11.527482Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10680,"took":"69.109361ms","hash":3851647939,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":20471808,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-09T06:24:11.527538Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3851647939,"revision":10680,"compact-revision":8288}