{"level":"warn","ts":"2026-05-26T05:46:13.007079Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T05:46:13.007189Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-05-26T05:46:13.007254Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T05:46:13.007267Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-05-26T05:46:13.007283Z","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-05-26T05:46:13.007861Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-05-26T05:46:13.007931Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-05-26T05:46:13.013868Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.741269ms"} {"level":"info","ts":"2026-05-26T05:46:13.024146Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-05-26T05:46:13.024261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T05:46:13.02429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-05-26T05:46:13.024313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T05:46:13.024342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-05-26T05:46:13.024399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-05-26T05:46:13.041341Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T05:46:13.043825Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T05:46:13.045183Z","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-05-26T05:46:13.047753Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T05:46:13.047989Z","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-05-26T05:46:13.047972Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T05:46:13.048061Z","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-05-26T05:46:13.048123Z","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-05-26T05:46:13.051237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-05-26T05:46:13.051484Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-05-26T05:46:13.052849Z","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-05-26T05:46:13.053047Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T05:46:13.053076Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T05:46:13.053174Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-05-26T05:46:13.05319Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-05-26T05:46:13.82498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T05:46:13.825045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T05:46:13.825089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-05-26T05:46:13.82511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-05-26T05:46:13.825122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-05-26T05:46:13.825149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-05-26T05:46:13.825178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-05-26T05:46:13.826537Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:46:13.827472Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T05:46:13.827483Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T05:46:13.82761Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T05:46:13.827852Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T05:46:13.827979Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T05:46:13.828115Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:46:13.828244Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:46:13.828366Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:46:13.830646Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-05-26T05:46:13.830842Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T05:47:44.95617Z","caller":"traceutil/trace.go:171","msg":"trace[1217894438] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"322.47698ms","start":"2026-05-26T05:47:44.633652Z","end":"2026-05-26T05:47:44.956129Z","steps":["trace[1217894438] 'process raft request' (duration: 322.221865ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:47:44.956213Z","caller":"traceutil/trace.go:171","msg":"trace[1174815277] transaction","detail":"{read_only:false; number_of_response:1; response_revision:666; }","duration":"318.771013ms","start":"2026-05-26T05:47:44.637415Z","end":"2026-05-26T05:47:44.956186Z","steps":["trace[1174815277] 'process raft request' (duration: 318.665931ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:47:44.956277Z","caller":"traceutil/trace.go:171","msg":"trace[461857878] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"319.683772ms","start":"2026-05-26T05:47:44.636549Z","end":"2026-05-26T05:47:44.956233Z","steps":["trace[461857878] 'process raft request' (duration: 319.469967ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:47:44.95638Z","caller":"traceutil/trace.go:171","msg":"trace[1578546968] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"300.499464ms","start":"2026-05-26T05:47:44.655851Z","end":"2026-05-26T05:47:44.956351Z","steps":["trace[1578546968] 'process raft request' (duration: 300.27837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:47:44.956762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:47:44.633635Z","time spent":"322.644964ms","remote":"127.0.0.1:60910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T05:47:44.957135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:47:44.655833Z","time spent":"300.593336ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T05:47:44.957209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:47:44.636527Z","time spent":"319.835425ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:47:44.957141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:47:44.637401Z","time spent":"318.885976ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:47:45.000837Z","caller":"traceutil/trace.go:171","msg":"trace[1563193442] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"301.542317ms","start":"2026-05-26T05:47:44.699267Z","end":"2026-05-26T05:47:45.000809Z","steps":["trace[1563193442] 'process raft request' (duration: 301.298152ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:47:45.000883Z","caller":"traceutil/trace.go:171","msg":"trace[1740343091] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"296.171475ms","start":"2026-05-26T05:47:44.704689Z","end":"2026-05-26T05:47:45.00086Z","steps":["trace[1740343091] 'process raft request' (duration: 296.027242ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:47:45.00098Z","caller":"traceutil/trace.go:171","msg":"trace[893374070] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"301.511536ms","start":"2026-05-26T05:47:44.699374Z","end":"2026-05-26T05:47:45.000886Z","steps":["trace[893374070] 'process raft request' (duration: 301.294941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:47:45.001016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:47:44.699251Z","time spent":"301.665369ms","remote":"127.0.0.1:60910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T05:47:45.001043Z","caller":"traceutil/trace.go:171","msg":"trace[2004927817] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"295.827268ms","start":"2026-05-26T05:47:44.705202Z","end":"2026-05-26T05:47:45.001029Z","steps":["trace[2004927817] 'process raft request' (duration: 295.555673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:47:45.001181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:47:44.699369Z","time spent":"301.711751ms","remote":"127.0.0.1:60910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T05:47:45.001238Z","caller":"traceutil/trace.go:171","msg":"trace[127121880] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"293.607781ms","start":"2026-05-26T05:47:44.707612Z","end":"2026-05-26T05:47:45.001219Z","steps":["trace[127121880] 'process raft request' (duration: 293.198133ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:47:45.001331Z","caller":"traceutil/trace.go:171","msg":"trace[1938423775] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"292.567679ms","start":"2026-05-26T05:47:44.70869Z","end":"2026-05-26T05:47:45.001258Z","steps":["trace[1938423775] 'process raft request' (duration: 292.157811ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:47:45.001518Z","caller":"traceutil/trace.go:171","msg":"trace[931113192] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"289.743301ms","start":"2026-05-26T05:47:44.711731Z","end":"2026-05-26T05:47:45.001474Z","steps":["trace[931113192] 'process raft request' (duration: 289.253761ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:47:45.002577Z","caller":"traceutil/trace.go:171","msg":"trace[1158840619] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"238.294445ms","start":"2026-05-26T05:47:44.764261Z","end":"2026-05-26T05:47:45.002555Z","steps":["trace[1158840619] 'process raft request' (duration: 238.185511ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:47:45.002708Z","caller":"traceutil/trace.go:171","msg":"trace[177739799] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"286.650708ms","start":"2026-05-26T05:47:44.716009Z","end":"2026-05-26T05:47:45.002659Z","steps":["trace[177739799] 'process raft request' (duration: 285.130846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:47:51.697376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.948861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:47:51.697631Z","caller":"traceutil/trace.go:171","msg":"trace[995550120] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"180.533637ms","start":"2026-05-26T05:47:51.517079Z","end":"2026-05-26T05:47:51.697613Z","steps":["trace[995550120] 'read index received' (duration: 10.842671ms)","trace[995550120] 'applied index is now lower than readState.Index' (duration: 169.672015ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:47:51.697709Z","caller":"traceutil/trace.go:171","msg":"trace[372863235] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"424.269981ms","start":"2026-05-26T05:47:51.27341Z","end":"2026-05-26T05:47:51.69768Z","steps":["trace[372863235] 'process raft request' (duration: 254.549775ms)","trace[372863235] 'compare' (duration: 168.43227ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:47:51.698004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:47:51.273385Z","time spent":"424.566568ms","remote":"127.0.0.1:60592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T05:47:51.698089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.977237ms","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-05-26T05:47:51.698175Z","caller":"traceutil/trace.go:171","msg":"trace[289781070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"181.11509ms","start":"2026-05-26T05:47:51.517038Z","end":"2026-05-26T05:47:51.698154Z","steps":["trace[289781070] 'agreement among raft nodes before linearized reading' (duration: 180.69399ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:48:07.86158Z","caller":"traceutil/trace.go:171","msg":"trace[2143534369] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"102.762713ms","start":"2026-05-26T05:48:07.758764Z","end":"2026-05-26T05:48:07.861526Z","steps":["trace[2143534369] 'process raft request' (duration: 102.521128ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:48:07.971328Z","caller":"traceutil/trace.go:171","msg":"trace[937584598] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"104.634421ms","start":"2026-05-26T05:48:07.866666Z","end":"2026-05-26T05:48:07.9713Z","steps":["trace[937584598] 'process raft request' (duration: 62.918212ms)","trace[937584598] 'compare' (duration: 41.477464ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:48:07.971607Z","caller":"traceutil/trace.go:171","msg":"trace[870193088] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"104.826456ms","start":"2026-05-26T05:48:07.866763Z","end":"2026-05-26T05:48:07.971589Z","steps":["trace[870193088] 'process raft request' (duration: 104.503969ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:49:21.206349Z","caller":"traceutil/trace.go:171","msg":"trace[325283684] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2076; }","duration":"131.777433ms","start":"2026-05-26T05:49:21.074543Z","end":"2026-05-26T05:49:21.206321Z","steps":["trace[325283684] 'read index received' (duration: 131.563349ms)","trace[325283684] 'applied index is now lower than readState.Index' (duration: 212.924µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:49:21.206493Z","caller":"traceutil/trace.go:171","msg":"trace[292755901] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"243.726235ms","start":"2026-05-26T05:49:20.962749Z","end":"2026-05-26T05:49:21.206475Z","steps":["trace[292755901] 'process raft request' (duration: 243.419398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:49:21.206643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.058739ms","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-05-26T05:49:21.206787Z","caller":"traceutil/trace.go:171","msg":"trace[291206376] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1996; }","duration":"132.245223ms","start":"2026-05-26T05:49:21.074486Z","end":"2026-05-26T05:49:21.206732Z","steps":["trace[291206376] 'agreement among raft nodes before linearized reading' (duration: 132.068919ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:49:21.220133Z","caller":"traceutil/trace.go:171","msg":"trace[1735454294] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"130.583877ms","start":"2026-05-26T05:49:21.089528Z","end":"2026-05-26T05:49:21.220112Z","steps":["trace[1735454294] 'process raft request' (duration: 130.371942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:49:21.220208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.73845ms","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-05-26T05:49:21.220318Z","caller":"traceutil/trace.go:171","msg":"trace[1615855836] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1997; }","duration":"130.876325ms","start":"2026-05-26T05:49:21.089415Z","end":"2026-05-26T05:49:21.220291Z","steps":["trace[1615855836] 'agreement among raft nodes before linearized reading' (duration: 130.626519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:49:21.220365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.336014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-205adb73-7bc3-458e-9e8c-fa86145788e2\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-26T05:49:21.220439Z","caller":"traceutil/trace.go:171","msg":"trace[692497618] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-205adb73-7bc3-458e-9e8c-fa86145788e2; range_end:; response_count:1; response_revision:1997; }","duration":"100.486207ms","start":"2026-05-26T05:49:21.119935Z","end":"2026-05-26T05:49:21.220421Z","steps":["trace[692497618] 'agreement among raft nodes before linearized reading' (duration: 100.238932ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:49:37.423975Z","caller":"traceutil/trace.go:171","msg":"trace[596615207] linearizableReadLoop","detail":"{readStateIndex:2217; appliedIndex:2216; }","duration":"145.683089ms","start":"2026-05-26T05:49:37.278268Z","end":"2026-05-26T05:49:37.423951Z","steps":["trace[596615207] 'read index received' (duration: 145.571077ms)","trace[596615207] 'applied index is now lower than readState.Index' (duration: 110.962µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:49:37.424117Z","caller":"traceutil/trace.go:171","msg":"trace[1575010628] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"154.713081ms","start":"2026-05-26T05:49:37.269362Z","end":"2026-05-26T05:49:37.424075Z","steps":["trace[1575010628] 'process raft request' (duration: 154.412504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:49:37.424215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.925604ms","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-05-26T05:49:37.424252Z","caller":"traceutil/trace.go:171","msg":"trace[1111588281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2132; }","duration":"146.012667ms","start":"2026-05-26T05:49:37.278228Z","end":"2026-05-26T05:49:37.424241Z","steps":["trace[1111588281] 'agreement among raft nodes before linearized reading' (duration: 145.859643ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:49:37.491686Z","caller":"traceutil/trace.go:171","msg":"trace[540396822] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"144.76794ms","start":"2026-05-26T05:49:37.346891Z","end":"2026-05-26T05:49:37.491659Z","steps":["trace[540396822] 'process raft request' (duration: 143.995233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:49:37.491931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.642192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:49:37.492011Z","caller":"traceutil/trace.go:171","msg":"trace[128129820] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2134; }","duration":"117.771075ms","start":"2026-05-26T05:49:37.374219Z","end":"2026-05-26T05:49:37.49199Z","steps":["trace[128129820] 'agreement among raft nodes before linearized reading' (duration: 117.441058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:50:16.468538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.314825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:50:16.468851Z","caller":"traceutil/trace.go:171","msg":"trace[840143256] transaction","detail":"{read_only:false; response_revision:2346; number_of_response:1; }","duration":"145.893563ms","start":"2026-05-26T05:50:16.322751Z","end":"2026-05-26T05:50:16.468645Z","steps":["trace[840143256] 'process raft request' (duration: 22.360313ms)","trace[840143256] 'compare' (duration: 122.889487ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:50:16.926052Z","caller":"traceutil/trace.go:171","msg":"trace[730560551] transaction","detail":"{read_only:false; response_revision:2348; number_of_response:1; }","duration":"180.564561ms","start":"2026-05-26T05:50:16.745462Z","end":"2026-05-26T05:50:16.926027Z","steps":["trace[730560551] 'process raft request' (duration: 177.051229ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:50:16.926247Z","caller":"traceutil/trace.go:171","msg":"trace[19780122] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"180.569201ms","start":"2026-05-26T05:50:16.745646Z","end":"2026-05-26T05:50:16.926215Z","steps":["trace[19780122] 'process raft request' (duration: 180.333116ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:50:16.926198Z","caller":"traceutil/trace.go:171","msg":"trace[408871706] linearizableReadLoop","detail":"{readStateIndex:2442; appliedIndex:2440; }","duration":"133.168419ms","start":"2026-05-26T05:50:16.793003Z","end":"2026-05-26T05:50:16.926171Z","steps":["trace[408871706] 'read index received' (duration: 129.530884ms)","trace[408871706] 'applied index is now lower than readState.Index' (duration: 3.635185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:50:16.926603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.568908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:50:16.926821Z","caller":"traceutil/trace.go:171","msg":"trace[1501452113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2349; }","duration":"133.860364ms","start":"2026-05-26T05:50:16.792946Z","end":"2026-05-26T05:50:16.926806Z","steps":["trace[1501452113] 'agreement among raft nodes before linearized reading' (duration: 133.566638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:50:16.927146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.444301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-26T05:50:16.927372Z","caller":"traceutil/trace.go:171","msg":"trace[135384990] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:2349; }","duration":"128.546214ms","start":"2026-05-26T05:50:16.798643Z","end":"2026-05-26T05:50:16.927189Z","steps":["trace[135384990] 'agreement among raft nodes before linearized reading' (duration: 128.184496ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:51:24.38964Z","caller":"traceutil/trace.go:171","msg":"trace[707054634] transaction","detail":"{read_only:false; response_revision:2910; number_of_response:1; }","duration":"103.557403ms","start":"2026-05-26T05:51:24.286041Z","end":"2026-05-26T05:51:24.389598Z","steps":["trace[707054634] 'process raft request' (duration: 103.355669ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:51:24.554987Z","caller":"traceutil/trace.go:171","msg":"trace[985284547] transaction","detail":"{read_only:false; response_revision:2911; number_of_response:1; }","duration":"132.723859ms","start":"2026-05-26T05:51:24.422225Z","end":"2026-05-26T05:51:24.554949Z","steps":["trace[985284547] 'process raft request' (duration: 115.533558ms)","trace[985284547] 'compare' (duration: 17.018517ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:55:16.701711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.566422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8c578b44-151c-4b64-84ed-5a80285faaf2\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-26T05:55:16.702094Z","caller":"traceutil/trace.go:171","msg":"trace[2077017156] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8c578b44-151c-4b64-84ed-5a80285faaf2; range_end:; response_count:1; response_revision:5081; }","duration":"102.053122ms","start":"2026-05-26T05:55:16.600018Z","end":"2026-05-26T05:55:16.702072Z","steps":["trace[2077017156] 'range keys from in-memory index tree' (duration: 100.896008ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:16.823992Z","caller":"traceutil/trace.go:171","msg":"trace[588155208] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"180.988643ms","start":"2026-05-26T05:55:16.642975Z","end":"2026-05-26T05:55:16.823964Z","steps":["trace[588155208] 'process raft request' (duration: 180.82261ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:17.100387Z","caller":"traceutil/trace.go:171","msg":"trace[1987283312] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"126.699625ms","start":"2026-05-26T05:55:16.973632Z","end":"2026-05-26T05:55:17.100332Z","steps":["trace[1987283312] 'process raft request' (duration: 75.97455ms)","trace[1987283312] 'compare' (duration: 50.441109ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:55:17.100376Z","caller":"traceutil/trace.go:171","msg":"trace[29457242] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"101.221415ms","start":"2026-05-26T05:55:16.999113Z","end":"2026-05-26T05:55:17.100334Z","steps":["trace[29457242] 'process raft request' (duration: 101.119433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:55:31.656585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.468404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-26T05:55:31.656687Z","caller":"traceutil/trace.go:171","msg":"trace[100391188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5351; }","duration":"324.654678ms","start":"2026-05-26T05:55:31.332013Z","end":"2026-05-26T05:55:31.656668Z","steps":["trace[100391188] 'range keys from in-memory index tree' (duration: 324.241779ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:31.656614Z","caller":"traceutil/trace.go:171","msg":"trace[895451058] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"178.03477ms","start":"2026-05-26T05:55:31.47856Z","end":"2026-05-26T05:55:31.656595Z","steps":["trace[895451058] 'process raft request' (duration: 129.928966ms)","trace[895451058] 'compare' (duration: 47.783867ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:55:31.656822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:55:31.33199Z","time spent":"324.818321ms","remote":"127.0.0.1:60616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T05:55:38.691027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.00579ms","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-05-26T05:55:38.691124Z","caller":"traceutil/trace.go:171","msg":"trace[1884857687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5542; }","duration":"135.132023ms","start":"2026-05-26T05:55:38.555972Z","end":"2026-05-26T05:55:38.691104Z","steps":["trace[1884857687] 'range keys from in-memory index tree' (duration: 134.890648ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:50.480496Z","caller":"traceutil/trace.go:171","msg":"trace[1256412574] linearizableReadLoop","detail":"{readStateIndex:5862; appliedIndex:5861; }","duration":"169.791402ms","start":"2026-05-26T05:55:50.310675Z","end":"2026-05-26T05:55:50.480466Z","steps":["trace[1256412574] 'read index received' (duration: 169.652319ms)","trace[1256412574] 'applied index is now lower than readState.Index' (duration: 137.513µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:55:50.480556Z","caller":"traceutil/trace.go:171","msg":"trace[1364803291] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"171.21019ms","start":"2026-05-26T05:55:50.309318Z","end":"2026-05-26T05:55:50.480528Z","steps":["trace[1364803291] 'process raft request' (duration: 170.983566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:55:50.48077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.081907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4f4d4d04-09c6-4c3d-8db8-88f5e4f5456d\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-05-26T05:55:50.480777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.652597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T05:55:50.480812Z","caller":"traceutil/trace.go:171","msg":"trace[100993555] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4f4d4d04-09c6-4c3d-8db8-88f5e4f5456d; range_end:; response_count:1; response_revision:5650; }","duration":"170.174499ms","start":"2026-05-26T05:55:50.310622Z","end":"2026-05-26T05:55:50.480796Z","steps":["trace[100993555] 'agreement among raft nodes before linearized reading' (duration: 170.011716ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:50.480864Z","caller":"traceutil/trace.go:171","msg":"trace[704290941] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5650; }","duration":"121.787019ms","start":"2026-05-26T05:55:50.359056Z","end":"2026-05-26T05:55:50.480843Z","steps":["trace[704290941] 'agreement among raft nodes before linearized reading' (duration: 121.619786ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:50.66428Z","caller":"traceutil/trace.go:171","msg":"trace[1554770994] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"176.386925ms","start":"2026-05-26T05:55:50.487838Z","end":"2026-05-26T05:55:50.664225Z","steps":["trace[1554770994] 'process raft request' (duration: 152.15524ms)","trace[1554770994] 'compare' (duration: 23.98533ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:55:50.66432Z","caller":"traceutil/trace.go:171","msg":"trace[846673827] transaction","detail":"{read_only:false; response_revision:5654; number_of_response:1; }","duration":"176.267213ms","start":"2026-05-26T05:55:50.488024Z","end":"2026-05-26T05:55:50.664291Z","steps":["trace[846673827] 'process raft request' (duration: 176.184241ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:50.664349Z","caller":"traceutil/trace.go:171","msg":"trace[1105763354] transaction","detail":"{read_only:false; response_revision:5653; number_of_response:1; }","duration":"176.410295ms","start":"2026-05-26T05:55:50.487875Z","end":"2026-05-26T05:55:50.664286Z","steps":["trace[1105763354] 'process raft request' (duration: 176.275943ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:50.664469Z","caller":"traceutil/trace.go:171","msg":"trace[509618239] linearizableReadLoop","detail":"{readStateIndex:5867; appliedIndex:5862; }","duration":"140.717278ms","start":"2026-05-26T05:55:50.523725Z","end":"2026-05-26T05:55:50.664442Z","steps":["trace[509618239] 'read index received' (duration: 12.324202ms)","trace[509618239] 'applied index is now lower than readState.Index' (duration: 128.391285ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:55:50.664528Z","caller":"traceutil/trace.go:171","msg":"trace[122864798] transaction","detail":"{read_only:false; response_revision:5655; number_of_response:1; }","duration":"176.211603ms","start":"2026-05-26T05:55:50.488301Z","end":"2026-05-26T05:55:50.664512Z","steps":["trace[122864798] 'process raft request' (duration: 175.948307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:55:50.66468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.508383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:55:50.664744Z","caller":"traceutil/trace.go:171","msg":"trace[363404674] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:5655; }","duration":"138.621904ms","start":"2026-05-26T05:55:50.526104Z","end":"2026-05-26T05:55:50.664726Z","steps":["trace[363404674] 'agreement among raft nodes before linearized reading' (duration: 138.502732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:55:50.665166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.36496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T05:55:50.665235Z","caller":"traceutil/trace.go:171","msg":"trace[161515075] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5655; }","duration":"141.501983ms","start":"2026-05-26T05:55:50.523716Z","end":"2026-05-26T05:55:50.665218Z","steps":["trace[161515075] 'agreement among raft nodes before linearized reading' (duration: 140.8275ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:50.962032Z","caller":"traceutil/trace.go:171","msg":"trace[1804196672] linearizableReadLoop","detail":"{readStateIndex:5868; appliedIndex:5867; }","duration":"142.377971ms","start":"2026-05-26T05:55:50.819629Z","end":"2026-05-26T05:55:50.962007Z","steps":["trace[1804196672] 'read index received' (duration: 142.200848ms)","trace[1804196672] 'applied index is now lower than readState.Index' (duration: 176.033µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:55:50.962183Z","caller":"traceutil/trace.go:171","msg":"trace[649364968] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"144.107577ms","start":"2026-05-26T05:55:50.818031Z","end":"2026-05-26T05:55:50.962139Z","steps":["trace[649364968] 'process raft request' (duration: 143.802651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:55:50.962235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.593695ms","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-05-26T05:55:50.962453Z","caller":"traceutil/trace.go:171","msg":"trace[1145216029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5656; }","duration":"142.8461ms","start":"2026-05-26T05:55:50.819575Z","end":"2026-05-26T05:55:50.962421Z","steps":["trace[1145216029] 'agreement among raft nodes before linearized reading' (duration: 142.530524ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:56:13.851015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2852} {"level":"info","ts":"2026-05-26T05:56:13.924274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2852,"took":"71.560064ms","hash":2320502183,"current-db-size-bytes":56918016,"current-db-size":"57 MB","current-db-size-in-use-bytes":32497664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T05:56:13.924349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2320502183,"revision":2852,"compact-revision":-1} {"level":"info","ts":"2026-05-26T06:01:13.857412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5807} {"level":"info","ts":"2026-05-26T06:01:13.940464Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5807,"took":"81.303293ms","hash":840699687,"current-db-size-bytes":56918016,"current-db-size":"57 MB","current-db-size-in-use-bytes":25833472,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-26T06:01:13.940518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":840699687,"revision":5807,"compact-revision":2852} {"level":"info","ts":"2026-05-26T06:01:46.862909Z","caller":"traceutil/trace.go:171","msg":"trace[1655962744] linearizableReadLoop","detail":"{readStateIndex:8635; appliedIndex:8634; }","duration":"131.154196ms","start":"2026-05-26T06:01:46.73173Z","end":"2026-05-26T06:01:46.862884Z","steps":["trace[1655962744] 'read index received' (duration: 77.632588ms)","trace[1655962744] 'applied index is now lower than readState.Index' (duration: 53.520488ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T06:01:46.863075Z","caller":"traceutil/trace.go:171","msg":"trace[1260487762] transaction","detail":"{read_only:false; response_revision:8338; number_of_response:1; }","duration":"171.392393ms","start":"2026-05-26T06:01:46.691647Z","end":"2026-05-26T06:01:46.863039Z","steps":["trace[1260487762] 'process raft request' (duration: 117.846145ms)","trace[1260487762] 'compare' (duration: 53.169441ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T06:01:46.863119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.36227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T06:01:46.863162Z","caller":"traceutil/trace.go:171","msg":"trace[240433886] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:8338; }","duration":"131.457953ms","start":"2026-05-26T06:01:46.731692Z","end":"2026-05-26T06:01:46.86315Z","steps":["trace[240433886] 'agreement among raft nodes before linearized reading' (duration: 131.33682ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:01:58.487332Z","caller":"traceutil/trace.go:171","msg":"trace[4128572] transaction","detail":"{read_only:false; response_revision:8398; number_of_response:1; }","duration":"198.522318ms","start":"2026-05-26T06:01:58.288773Z","end":"2026-05-26T06:01:58.487295Z","steps":["trace[4128572] 'process raft request' (duration: 198.238212ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:01:58.511711Z","caller":"traceutil/trace.go:171","msg":"trace[1184567835] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"197.047627ms","start":"2026-05-26T06:01:58.314636Z","end":"2026-05-26T06:01:58.511683Z","steps":["trace[1184567835] 'process raft request' (duration: 196.833792ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:01:58.511966Z","caller":"traceutil/trace.go:171","msg":"trace[1964283107] linearizableReadLoop","detail":"{readStateIndex:8701; appliedIndex:8698; }","duration":"155.257789ms","start":"2026-05-26T06:01:58.356668Z","end":"2026-05-26T06:01:58.511926Z","steps":["trace[1964283107] 'read index received' (duration: 130.417593ms)","trace[1964283107] 'applied index is now lower than readState.Index' (duration: 24.837505ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T06:01:58.512019Z","caller":"traceutil/trace.go:171","msg":"trace[1670620445] transaction","detail":"{read_only:false; response_revision:8400; number_of_response:1; }","duration":"185.252048ms","start":"2026-05-26T06:01:58.326721Z","end":"2026-05-26T06:01:58.511973Z","steps":["trace[1670620445] 'process raft request' (duration: 184.907031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:01:58.512146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.458072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-26T06:01:58.512197Z","caller":"traceutil/trace.go:171","msg":"trace[249788321] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8400; }","duration":"155.574075ms","start":"2026-05-26T06:01:58.356611Z","end":"2026-05-26T06:01:58.512185Z","steps":["trace[249788321] 'agreement among raft nodes before linearized reading' (duration: 155.415022ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:01:59.306332Z","caller":"traceutil/trace.go:171","msg":"trace[486661168] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"112.528869ms","start":"2026-05-26T06:01:59.193777Z","end":"2026-05-26T06:01:59.306306Z","steps":["trace[486661168] 'process raft request' (duration: 112.382596ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:01:59.937744Z","caller":"traceutil/trace.go:171","msg":"trace[1659285961] linearizableReadLoop","detail":"{readStateIndex:8707; appliedIndex:8706; }","duration":"183.190696ms","start":"2026-05-26T06:01:59.75453Z","end":"2026-05-26T06:01:59.937721Z","steps":["trace[1659285961] 'read index received' (duration: 182.967411ms)","trace[1659285961] 'applied index is now lower than readState.Index' (duration: 222.455µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T06:01:59.937858Z","caller":"traceutil/trace.go:171","msg":"trace[1169743848] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"291.763565ms","start":"2026-05-26T06:01:59.646083Z","end":"2026-05-26T06:01:59.937847Z","steps":["trace[1169743848] 'process raft request' (duration: 291.479318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:01:59.938192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.600971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T06:01:59.938334Z","caller":"traceutil/trace.go:171","msg":"trace[13002956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8406; }","duration":"144.815296ms","start":"2026-05-26T06:01:59.793483Z","end":"2026-05-26T06:01:59.938298Z","steps":["trace[13002956] 'agreement among raft nodes before linearized reading' (duration: 144.575121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:01:59.938352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.807309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-26T06:01:59.938399Z","caller":"traceutil/trace.go:171","msg":"trace[1284969362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8406; }","duration":"183.875291ms","start":"2026-05-26T06:01:59.754513Z","end":"2026-05-26T06:01:59.938388Z","steps":["trace[1284969362] 'agreement among raft nodes before linearized reading' (duration: 183.713307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:02:26.95543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.26744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T06:02:26.95555Z","caller":"traceutil/trace.go:171","msg":"trace[829063370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8605; }","duration":"160.448603ms","start":"2026-05-26T06:02:26.795077Z","end":"2026-05-26T06:02:26.955526Z","steps":["trace[829063370] 'range keys from in-memory index tree' (duration: 160.079096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:02:26.955839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.247314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-26T06:02:26.955906Z","caller":"traceutil/trace.go:171","msg":"trace[1526850545] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:8605; }","duration":"116.369187ms","start":"2026-05-26T06:02:26.83952Z","end":"2026-05-26T06:02:26.955889Z","steps":["trace[1526850545] 'range keys from in-memory index tree' (duration: 116.05511ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:02:27.117513Z","caller":"traceutil/trace.go:171","msg":"trace[2141977745] linearizableReadLoop","detail":"{readStateIndex:8914; appliedIndex:8914; }","duration":"159.706818ms","start":"2026-05-26T06:02:26.95776Z","end":"2026-05-26T06:02:27.117467Z","steps":["trace[2141977745] 'read index received' (duration: 159.694158ms)","trace[2141977745] 'applied index is now lower than readState.Index' (duration: 9.91µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T06:02:27.117725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.938203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T06:02:27.117777Z","caller":"traceutil/trace.go:171","msg":"trace[358374906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8605; }","duration":"160.072695ms","start":"2026-05-26T06:02:26.957686Z","end":"2026-05-26T06:02:27.117759Z","steps":["trace[358374906] 'agreement among raft nodes before linearized reading' (duration: 159.958973ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:02:27.136799Z","caller":"traceutil/trace.go:171","msg":"trace[47690714] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"175.337106ms","start":"2026-05-26T06:02:26.961448Z","end":"2026-05-26T06:02:27.136786Z","steps":["trace[47690714] 'process raft request' (duration: 175.00967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:02:30.4426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.970727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lblmr\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-26T06:02:30.442661Z","caller":"traceutil/trace.go:171","msg":"trace[504665513] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lblmr; range_end:; response_count:1; response_revision:8625; }","duration":"111.104411ms","start":"2026-05-26T06:02:30.331541Z","end":"2026-05-26T06:02:30.442646Z","steps":["trace[504665513] 'range keys from in-memory index tree' (duration: 110.769424ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:02:39.74898Z","caller":"traceutil/trace.go:171","msg":"trace[306398184] transaction","detail":"{read_only:false; response_revision:8716; number_of_response:1; }","duration":"131.77283ms","start":"2026-05-26T06:02:39.617168Z","end":"2026-05-26T06:02:39.74894Z","steps":["trace[306398184] 'process raft request' (duration: 131.521985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:02:39.914275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.279277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T06:02:39.914382Z","caller":"traceutil/trace.go:171","msg":"trace[1726582815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8716; }","duration":"119.425059ms","start":"2026-05-26T06:02:39.794931Z","end":"2026-05-26T06:02:39.914356Z","steps":["trace[1726582815] 'range keys from in-memory index tree' (duration: 119.150124ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:02:40.46962Z","caller":"traceutil/trace.go:171","msg":"trace[648304300] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"189.661067ms","start":"2026-05-26T06:02:40.279926Z","end":"2026-05-26T06:02:40.469587Z","steps":["trace[648304300] 'process raft request' (duration: 119.200325ms)","trace[648304300] 'compare' (duration: 70.32589ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T06:02:40.469701Z","caller":"traceutil/trace.go:171","msg":"trace[1730216269] linearizableReadLoop","detail":"{readStateIndex:9032; appliedIndex:9031; }","duration":"112.913586ms","start":"2026-05-26T06:02:40.356754Z","end":"2026-05-26T06:02:40.469667Z","steps":["trace[1730216269] 'read index received' (duration: 42.461233ms)","trace[1730216269] 'applied index is now lower than readState.Index' (duration: 70.446833ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T06:02:40.469818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.047218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T06:02:40.469893Z","caller":"traceutil/trace.go:171","msg":"trace[281393482] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:8720; }","duration":"113.172131ms","start":"2026-05-26T06:02:40.356704Z","end":"2026-05-26T06:02:40.469876Z","steps":["trace[281393482] 'agreement among raft nodes before linearized reading' (duration: 113.038148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:02:40.807468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.439803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-jdqx9\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-05-26T06:02:40.807558Z","caller":"traceutil/trace.go:171","msg":"trace[708883392] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-jdqx9; range_end:; response_count:1; response_revision:8720; }","duration":"190.588496ms","start":"2026-05-26T06:02:40.61695Z","end":"2026-05-26T06:02:40.807538Z","steps":["trace[708883392] 'range keys from in-memory index tree' (duration: 190.24824ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:02:40.807621Z","caller":"traceutil/trace.go:171","msg":"trace[1071163497] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"190.404183ms","start":"2026-05-26T06:02:40.617172Z","end":"2026-05-26T06:02:40.807576Z","steps":["trace[1071163497] 'process raft request' (duration: 159.675388ms)","trace[1071163497] 'compare' (duration: 30.49096ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T06:02:40.848108Z","caller":"traceutil/trace.go:171","msg":"trace[521925567] linearizableReadLoop","detail":"{readStateIndex:9035; appliedIndex:9033; }","duration":"163.089026ms","start":"2026-05-26T06:02:40.68499Z","end":"2026-05-26T06:02:40.848079Z","steps":["trace[521925567] 'read index received' (duration: 91.9648ms)","trace[521925567] 'applied index is now lower than readState.Index' (duration: 71.122876ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T06:02:40.848162Z","caller":"traceutil/trace.go:171","msg":"trace[2121241795] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"180.637073ms","start":"2026-05-26T06:02:40.667487Z","end":"2026-05-26T06:02:40.848124Z","steps":["trace[2121241795] 'process raft request' (duration: 180.246505ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:02:40.848249Z","caller":"traceutil/trace.go:171","msg":"trace[584502694] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"149.080502ms","start":"2026-05-26T06:02:40.699118Z","end":"2026-05-26T06:02:40.848198Z","steps":["trace[584502694] 'process raft request' (duration: 148.788406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:02:40.848345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.973042ms","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-05-26T06:02:40.848402Z","caller":"traceutil/trace.go:171","msg":"trace[1527924355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8723; }","duration":"128.053973ms","start":"2026-05-26T06:02:40.720333Z","end":"2026-05-26T06:02:40.848387Z","steps":["trace[1527924355] 'agreement among raft nodes before linearized reading' (duration: 127.921141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:02:40.848412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.394672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-05-26T06:02:40.848829Z","caller":"traceutil/trace.go:171","msg":"trace[522761981] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8723; }","duration":"163.607007ms","start":"2026-05-26T06:02:40.68494Z","end":"2026-05-26T06:02:40.848547Z","steps":["trace[522761981] 'agreement among raft nodes before linearized reading' (duration: 163.29704ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:03:03.521759Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245b.wal"} {"level":"info","ts":"2026-05-26T06:04:22.67083Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T06:04:22.676682Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-26T06:04:22.676823Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-26T06:06:13.865119Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7987} {"level":"info","ts":"2026-05-26T06:06:13.92472Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7987,"took":"57.544155ms","hash":2976277668,"current-db-size-bytes":56918016,"current-db-size":"57 MB","current-db-size-in-use-bytes":29319168,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-26T06:06:13.924777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2976277668,"revision":7987,"compact-revision":5807} {"level":"warn","ts":"2026-05-26T06:07:21.904028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.004593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T06:07:21.904119Z","caller":"traceutil/trace.go:171","msg":"trace[74516748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11217; }","duration":"108.138295ms","start":"2026-05-26T06:07:21.795964Z","end":"2026-05-26T06:07:21.904102Z","steps":["trace[74516748] 'range keys from in-memory index tree' (duration: 107.942782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:07:21.904623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.121761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T06:07:21.904736Z","caller":"traceutil/trace.go:171","msg":"trace[1723334481] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11217; }","duration":"168.334205ms","start":"2026-05-26T06:07:21.736381Z","end":"2026-05-26T06:07:21.904715Z","steps":["trace[1723334481] 'count revisions from in-memory index tree' (duration: 167.991978ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:07:38.753586Z","caller":"traceutil/trace.go:171","msg":"trace[2088682686] transaction","detail":"{read_only:false; response_revision:11311; number_of_response:1; }","duration":"167.927536ms","start":"2026-05-26T06:07:38.585634Z","end":"2026-05-26T06:07:38.753561Z","steps":["trace[2088682686] 'process raft request' (duration: 167.764693ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:07:38.763311Z","caller":"traceutil/trace.go:171","msg":"trace[1602724204] linearizableReadLoop","detail":"{readStateIndex:11701; appliedIndex:11699; }","duration":"164.827323ms","start":"2026-05-26T06:07:38.598458Z","end":"2026-05-26T06:07:38.763286Z","steps":["trace[1602724204] 'read index received' (duration: 155.068053ms)","trace[1602724204] 'applied index is now lower than readState.Index' (duration: 9.75799ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T06:07:38.763357Z","caller":"traceutil/trace.go:171","msg":"trace[439436649] transaction","detail":"{read_only:false; response_revision:11312; number_of_response:1; }","duration":"174.325648ms","start":"2026-05-26T06:07:38.589004Z","end":"2026-05-26T06:07:38.76333Z","steps":["trace[439436649] 'process raft request' (duration: 174.120584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:07:38.763525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.033727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T06:07:38.763594Z","caller":"traceutil/trace.go:171","msg":"trace[351787712] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:11312; }","duration":"165.14748ms","start":"2026-05-26T06:07:38.59843Z","end":"2026-05-26T06:07:38.763577Z","steps":["trace[351787712] 'agreement among raft nodes before linearized reading' (duration: 164.996817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:07:38.76359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.769547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-volume-7d6cc597df-vxvqb.18b30880e462d7ff\" ","response":"range_response_count:1 size:804"} {"level":"info","ts":"2026-05-26T06:07:38.763656Z","caller":"traceutil/trace.go:171","msg":"trace[2133900909] range","detail":"{range_begin:/registry/events/openstack/cinder-volume-7d6cc597df-vxvqb.18b30880e462d7ff; range_end:; response_count:1; response_revision:11312; }","duration":"150.878249ms","start":"2026-05-26T06:07:38.61276Z","end":"2026-05-26T06:07:38.763638Z","steps":["trace[2133900909] 'agreement among raft nodes before linearized reading' (duration: 150.707316ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:11:13.871296Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10509} {"level":"info","ts":"2026-05-26T06:11:13.954798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10509,"took":"80.919074ms","hash":694922935,"current-db-size-bytes":56918016,"current-db-size":"57 MB","current-db-size-in-use-bytes":27525120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-26T06:11:13.954873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":694922935,"revision":10509,"compact-revision":7987} {"level":"info","ts":"2026-05-26T06:14:14.313432Z","caller":"traceutil/trace.go:171","msg":"trace[1594319410] linearizableReadLoop","detail":"{readStateIndex:14441; appliedIndex:14440; }","duration":"282.749014ms","start":"2026-05-26T06:14:14.030656Z","end":"2026-05-26T06:14:14.313405Z","steps":["trace[1594319410] 'read index received' (duration: 282.733124ms)","trace[1594319410] 'applied index is now lower than readState.Index' (duration: 14.05µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T06:14:14.31344Z","caller":"traceutil/trace.go:171","msg":"trace[490520209] transaction","detail":"{read_only:false; response_revision:13958; number_of_response:1; }","duration":"329.851531ms","start":"2026-05-26T06:14:13.983562Z","end":"2026-05-26T06:14:14.313413Z","steps":["trace[490520209] 'process raft request' (duration: 329.686108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:14:14.313708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T06:14:13.983552Z","time spent":"330.070816ms","remote":"127.0.0.1:60616","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-05-26T06:14:14.313696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.008639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-26T06:14:14.313859Z","caller":"traceutil/trace.go:171","msg":"trace[1437168210] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:13958; }","duration":"283.211864ms","start":"2026-05-26T06:14:14.030628Z","end":"2026-05-26T06:14:14.31384Z","steps":["trace[1437168210] 'agreement among raft nodes before linearized reading' (duration: 282.878227ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:14:14.337022Z","caller":"traceutil/trace.go:171","msg":"trace[428034230] transaction","detail":"{read_only:false; response_revision:13959; number_of_response:1; }","duration":"163.705795ms","start":"2026-05-26T06:14:14.173293Z","end":"2026-05-26T06:14:14.336999Z","steps":["trace[428034230] 'process raft request' (duration: 163.43502ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:14:14.33715Z","caller":"traceutil/trace.go:171","msg":"trace[330581523] transaction","detail":"{read_only:false; response_revision:13960; number_of_response:1; }","duration":"163.386369ms","start":"2026-05-26T06:14:14.173743Z","end":"2026-05-26T06:14:14.337129Z","steps":["trace[330581523] 'process raft request' (duration: 163.187475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:14:35.917626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.181827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-26T06:14:35.917652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.059302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T06:14:35.917656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.850629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lblmr\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-05-26T06:14:35.917635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.417317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T06:14:35.917748Z","caller":"traceutil/trace.go:171","msg":"trace[190342970] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:14266; }","duration":"192.291428ms","start":"2026-05-26T06:14:35.725428Z","end":"2026-05-26T06:14:35.917719Z","steps":["trace[190342970] 'count revisions from in-memory index tree' (duration: 191.970221ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:14:35.91779Z","caller":"traceutil/trace.go:171","msg":"trace[1727955970] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:14266; }","duration":"152.609121ms","start":"2026-05-26T06:14:35.765169Z","end":"2026-05-26T06:14:35.917778Z","steps":["trace[1727955970] 'count revisions from in-memory index tree' (duration: 152.327976ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:14:35.917783Z","caller":"traceutil/trace.go:171","msg":"trace[1063275707] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lblmr; range_end:; response_count:1; response_revision:14266; }","duration":"163.077463ms","start":"2026-05-26T06:14:35.754678Z","end":"2026-05-26T06:14:35.917755Z","steps":["trace[1063275707] 'range keys from in-memory index tree' (duration: 162.585833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:14:35.917824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.470949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T06:14:35.917907Z","caller":"traceutil/trace.go:171","msg":"trace[2086208839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14266; }","duration":"125.575072ms","start":"2026-05-26T06:14:35.792316Z","end":"2026-05-26T06:14:35.917891Z","steps":["trace[2086208839] 'range keys from in-memory index tree' (duration: 125.378808ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:14:35.917718Z","caller":"traceutil/trace.go:171","msg":"trace[1706272729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14266; }","duration":"167.32549ms","start":"2026-05-26T06:14:35.750376Z","end":"2026-05-26T06:14:35.917701Z","steps":["trace[1706272729] 'range keys from in-memory index tree' (duration: 167.020114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:14:35.917674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.608455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-002cde98-9e9b-4576-a3ed-e2cd977a5a74\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-26T06:14:35.91833Z","caller":"traceutil/trace.go:171","msg":"trace[1375946243] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-002cde98-9e9b-4576-a3ed-e2cd977a5a74; range_end:; response_count:1; response_revision:14266; }","duration":"167.32266ms","start":"2026-05-26T06:14:35.750987Z","end":"2026-05-26T06:14:35.91831Z","steps":["trace[1375946243] 'range keys from in-memory index tree' (duration: 166.470503ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:15:52.597131Z","caller":"traceutil/trace.go:171","msg":"trace[1864698830] linearizableReadLoop","detail":"{readStateIndex:15547; appliedIndex:15546; }","duration":"280.664437ms","start":"2026-05-26T06:15:52.316441Z","end":"2026-05-26T06:15:52.597105Z","steps":["trace[1864698830] 'read index received' (duration: 280.369761ms)","trace[1864698830] 'applied index is now lower than readState.Index' (duration: 293.276µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T06:15:52.597274Z","caller":"traceutil/trace.go:171","msg":"trace[1875772447] transaction","detail":"{read_only:false; response_revision:15028; number_of_response:1; }","duration":"281.048475ms","start":"2026-05-26T06:15:52.316178Z","end":"2026-05-26T06:15:52.597226Z","steps":["trace[1875772447] 'process raft request' (duration: 280.732628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:15:52.597679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.012724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-26T06:15:52.597811Z","caller":"traceutil/trace.go:171","msg":"trace[1428933408] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15028; }","duration":"125.157067ms","start":"2026-05-26T06:15:52.472633Z","end":"2026-05-26T06:15:52.59779Z","steps":["trace[1428933408] 'agreement among raft nodes before linearized reading' (duration: 124.885682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T06:15:52.598358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.883331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737448"} {"level":"info","ts":"2026-05-26T06:15:52.598464Z","caller":"traceutil/trace.go:171","msg":"trace[19491733] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15028; }","duration":"282.033015ms","start":"2026-05-26T06:15:52.316407Z","end":"2026-05-26T06:15:52.59844Z","steps":["trace[19491733] 'agreement among raft nodes before linearized reading' (duration: 280.837831ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T06:16:13.878591Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12686} {"level":"info","ts":"2026-05-26T06:16:13.959362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12686,"took":"78.501351ms","hash":80867759,"current-db-size-bytes":56918016,"current-db-size":"57 MB","current-db-size-in-use-bytes":30683136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-26T06:16:13.959427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":80867759,"revision":12686,"compact-revision":10509} {"level":"info","ts":"2026-05-26T06:21:13.884193Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15137} {"level":"info","ts":"2026-05-26T06:21:13.975265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15137,"took":"88.140052ms","hash":655909257,"current-db-size-bytes":56918016,"current-db-size":"57 MB","current-db-size-in-use-bytes":31883264,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T06:21:13.975344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":655909257,"revision":15137,"compact-revision":12686} {"level":"info","ts":"2026-05-26T06:26:13.891005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17413} {"level":"info","ts":"2026-05-26T06:26:13.981651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17413,"took":"85.681081ms","hash":1418758004,"current-db-size-bytes":56918016,"current-db-size":"57 MB","current-db-size-in-use-bytes":26849280,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-26T06:26:13.981703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1418758004,"revision":17413,"compact-revision":15137} {"level":"info","ts":"2026-05-26T06:27:14.357681Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T06:27:14.362869Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-26T06:27:14.362973Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}