{"level":"warn","ts":"2026-07-06T01:49:10.871073Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T01:49:10.871224Z","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-07-06T01:49:10.871308Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T01:49:10.871321Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-07-06T01:49:10.871348Z","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-07-06T01:49:10.872122Z","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-07-06T01:49:10.872209Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://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-07-06T01:49:10.876679Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.242869ms"} {"level":"info","ts":"2026-07-06T01:49:10.88932Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-07-06T01:49:10.88943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-07-06T01:49:10.889462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-07-06T01:49:10.889479Z","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-07-06T01:49:10.889508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-07-06T01:49:10.889563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-07-06T01:49:10.896172Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-06T01:49:10.897385Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-06T01:49:10.899826Z","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-07-06T01:49:10.905352Z","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-07-06T01:49:10.905759Z","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-07-06T01:49:10.905913Z","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-07-06T01:49:10.906652Z","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-07-06T01:49:10.906677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-07-06T01:49:10.906686Z","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-07-06T01:49:10.906803Z","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-07-06T01:49:10.911306Z","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-07-06T01:49:10.911491Z","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-07-06T01:49:10.911515Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-06T01:49:10.912371Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-07-06T01:49:10.912593Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-07-06T01:49:11.293867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-07-06T01:49:11.293946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-06T01:49:11.293979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-07-06T01:49:11.294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-07-06T01:49:11.294013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-07-06T01:49:11.294035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-07-06T01:49:11.294053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-07-06T01:49:11.306262Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T01:49:11.307329Z","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-07-06T01:49:11.307407Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T01:49:11.307483Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T01:49:11.307748Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-06T01:49:11.307784Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-06T01:49:11.310593Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-06T01:49:11.311236Z","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-07-06T01:49:11.311367Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T01:49:11.311445Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T01:49:11.317431Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-07-06T01:49:27.410839Z","caller":"traceutil/trace.go:171","msg":"trace[10567795] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"173.483798ms","start":"2026-07-06T01:49:27.237337Z","end":"2026-07-06T01:49:27.410821Z","steps":["trace[10567795] 'process raft request' (duration: 148.828511ms)","trace[10567795] 'compare' (duration: 24.47596ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:49:31.182371Z","caller":"traceutil/trace.go:171","msg":"trace[559426152] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:393; }","duration":"178.675135ms","start":"2026-07-06T01:49:31.003676Z","end":"2026-07-06T01:49:31.182351Z","steps":["trace[559426152] 'read index received' (duration: 178.562922ms)","trace[559426152] 'applied index is now lower than readState.Index' (duration: 111.433µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:49:31.182651Z","caller":"traceutil/trace.go:171","msg":"trace[1469796567] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"562.713505ms","start":"2026-07-06T01:49:30.619929Z","end":"2026-07-06T01:49:31.182643Z","steps":["trace[1469796567] 'process raft request' (duration: 562.231529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:31.183035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:30.61991Z","time spent":"562.761156ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:49:31.183174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.498333ms","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-07-06T01:49:31.183202Z","caller":"traceutil/trace.go:171","msg":"trace[387483941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:379; }","duration":"179.554815ms","start":"2026-07-06T01:49:31.003639Z","end":"2026-07-06T01:49:31.183194Z","steps":["trace[387483941] 'agreement among raft nodes before linearized reading' (duration: 179.508184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:31.1833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.36461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-06T01:49:31.183317Z","caller":"traceutil/trace.go:171","msg":"trace[470754332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:379; }","duration":"176.400031ms","start":"2026-07-06T01:49:31.006912Z","end":"2026-07-06T01:49:31.183312Z","steps":["trace[470754332] 'agreement among raft nodes before linearized reading' (duration: 176.36942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:31.934128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.568471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:49:31.934246Z","caller":"traceutil/trace.go:171","msg":"trace[41740042] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"743.806607ms","start":"2026-07-06T01:49:31.190416Z","end":"2026-07-06T01:49:31.934222Z","steps":["trace[41740042] 'process raft request' (duration: 153.870954ms)","trace[41740042] 'compare' (duration: 588.852097ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:49:31.934322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:31.190398Z","time spent":"743.884909ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:49:32.926632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.777717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:49:32.926723Z","caller":"traceutil/trace.go:171","msg":"trace[1120976793] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:394; }","duration":"1.682700105s","start":"2026-07-06T01:49:31.244007Z","end":"2026-07-06T01:49:32.926707Z","steps":["trace[1120976793] 'read index received' (duration: 100.247546ms)","trace[1120976793] 'applied index is now lower than readState.Index' (duration: 1.582451389s)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:49:32.926909Z","caller":"traceutil/trace.go:171","msg":"trace[1920235504] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"1.736391636s","start":"2026-07-06T01:49:31.190505Z","end":"2026-07-06T01:49:32.926896Z","steps":["trace[1920235504] 'process raft request' (duration: 878.265758ms)","trace[1920235504] 'compare' (duration: 857.473106ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:49:32.926965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:31.190476Z","time spent":"1.736460248s","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:49:32.927122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.683113889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-06T01:49:32.927148Z","caller":"traceutil/trace.go:171","msg":"trace[1978409580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:381; }","duration":"1.683184321s","start":"2026-07-06T01:49:31.243955Z","end":"2026-07-06T01:49:32.927139Z","steps":["trace[1978409580] 'agreement among raft nodes before linearized reading' (duration: 1.68313307s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:32.92717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:31.243931Z","time spent":"1.683233032s","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T01:49:32.927281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.599366514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:49:32.927306Z","caller":"traceutil/trace.go:171","msg":"trace[1155291655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:381; }","duration":"1.599422655s","start":"2026-07-06T01:49:31.327876Z","end":"2026-07-06T01:49:32.927299Z","steps":["trace[1155291655] 'agreement among raft nodes before linearized reading' (duration: 1.599383414s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:32.927326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:31.327858Z","time spent":"1.599462787s","remote":"127.0.0.1:40122","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-07-06T01:49:49.503648Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203422962642238,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T01:49:49.662217Z","caller":"traceutil/trace.go:171","msg":"trace[1860317825] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"660.476036ms","start":"2026-07-06T01:49:49.001711Z","end":"2026-07-06T01:49:49.662187Z","steps":["trace[1860317825] 'process raft request' (duration: 660.325102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.66237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.001683Z","time spent":"660.61415ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T01:49:49.670331Z","caller":"traceutil/trace.go:171","msg":"trace[1145514250] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"665.465022ms","start":"2026-07-06T01:49:49.004844Z","end":"2026-07-06T01:49:49.670309Z","steps":["trace[1145514250] 'process raft request' (duration: 665.08672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.670491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.004831Z","time spent":"665.576076ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T01:49:49.670581Z","caller":"traceutil/trace.go:171","msg":"trace[612415361] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"657.614746ms","start":"2026-07-06T01:49:49.012942Z","end":"2026-07-06T01:49:49.670557Z","steps":["trace[612415361] 'process raft request' (duration: 657.10288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.670656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.012917Z","time spent":"657.711099ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T01:49:49.670829Z","caller":"traceutil/trace.go:171","msg":"trace[952126154] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"650.944506ms","start":"2026-07-06T01:49:49.019835Z","end":"2026-07-06T01:49:49.67078Z","steps":["trace[952126154] 'process raft request' (duration: 650.693138ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:49:49.670874Z","caller":"traceutil/trace.go:171","msg":"trace[1741308848] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"650.116929ms","start":"2026-07-06T01:49:49.020734Z","end":"2026-07-06T01:49:49.670851Z","steps":["trace[1741308848] 'process raft request' (duration: 650.001445ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:49:49.670908Z","caller":"traceutil/trace.go:171","msg":"trace[1801691123] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"649.743178ms","start":"2026-07-06T01:49:49.02115Z","end":"2026-07-06T01:49:49.670893Z","steps":["trace[1801691123] 'process raft request' (duration: 649.658696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.671167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.021124Z","time spent":"649.81305ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T01:49:49.671171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.020725Z","time spent":"650.181481ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T01:49:49.671169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.019803Z","time spent":"651.10293ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T01:49:49.671325Z","caller":"traceutil/trace.go:171","msg":"trace[1693275364] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"649.439359ms","start":"2026-07-06T01:49:49.021871Z","end":"2026-07-06T01:49:49.67131Z","steps":["trace[1693275364] 'process raft request' (duration: 648.981254ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:49:49.67134Z","caller":"traceutil/trace.go:171","msg":"trace[1694531400] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"648.301373ms","start":"2026-07-06T01:49:49.023029Z","end":"2026-07-06T01:49:49.67133Z","steps":["trace[1694531400] 'process raft request' (duration: 647.867789ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:49:49.671369Z","caller":"traceutil/trace.go:171","msg":"trace[1711916636] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"636.029908ms","start":"2026-07-06T01:49:49.03533Z","end":"2026-07-06T01:49:49.67136Z","steps":["trace[1711916636] 'process raft request' (duration: 635.612775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.67137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.021847Z","time spent":"649.501841ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T01:49:49.671379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.023021Z","time spent":"648.336184ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T01:49:49.671405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.035282Z","time spent":"636.10527ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T01:49:49.671437Z","caller":"traceutil/trace.go:171","msg":"trace[1831088469] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"608.486662ms","start":"2026-07-06T01:49:49.062939Z","end":"2026-07-06T01:49:49.671426Z","steps":["trace[1831088469] 'process raft request' (duration: 608.363108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.671463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.062913Z","time spent":"608.536114ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T01:49:49.671622Z","caller":"traceutil/trace.go:171","msg":"trace[1824621470] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"596.557468ms","start":"2026-07-06T01:49:49.075054Z","end":"2026-07-06T01:49:49.671612Z","steps":["trace[1824621470] 'process raft request' (duration: 596.30586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.671669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.075037Z","time spent":"596.61053ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T01:49:49.671882Z","caller":"traceutil/trace.go:171","msg":"trace[366750807] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:501; }","duration":"669.064105ms","start":"2026-07-06T01:49:49.002804Z","end":"2026-07-06T01:49:49.671868Z","steps":["trace[366750807] 'read index received' (duration: 659.33393ms)","trace[366750807] 'applied index is now lower than readState.Index' (duration: 9.729565ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:49:49.671931Z","caller":"traceutil/trace.go:171","msg":"trace[1226660423] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"508.005637ms","start":"2026-07-06T01:49:49.163918Z","end":"2026-07-06T01:49:49.671924Z","steps":["trace[1226660423] 'process raft request' (duration: 507.780259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.67197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.163885Z","time spent":"508.059269ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T01:49:49.67205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.23502ms","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-07-06T01:49:49.672083Z","caller":"traceutil/trace.go:171","msg":"trace[924312902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"669.313212ms","start":"2026-07-06T01:49:49.002761Z","end":"2026-07-06T01:49:49.672074Z","steps":["trace[924312902] 'agreement among raft nodes before linearized reading' (duration: 669.198809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.672104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.002741Z","time spent":"669.358384ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T01:49:49.684772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.037019ms","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-07-06T01:49:49.684844Z","caller":"traceutil/trace.go:171","msg":"trace[1216968614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:500; }","duration":"680.131852ms","start":"2026-07-06T01:49:49.004695Z","end":"2026-07-06T01:49:49.684827Z","steps":["trace[1216968614] 'agreement among raft nodes before linearized reading' (duration: 680.010158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.684967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.004689Z","time spent":"680.268326ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T01:49:49.685487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.483303ms","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-07-06T01:49:49.685513Z","caller":"traceutil/trace.go:171","msg":"trace[1616249193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:500; }","duration":"347.529955ms","start":"2026-07-06T01:49:49.337977Z","end":"2026-07-06T01:49:49.685507Z","steps":["trace[1616249193] 'agreement among raft nodes before linearized reading' (duration: 347.485154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.685587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.337966Z","time spent":"347.611137ms","remote":"127.0.0.1:40344","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-07-06T01:49:49.685771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.156789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:49:49.685847Z","caller":"traceutil/trace.go:171","msg":"trace[1987507350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"358.293174ms","start":"2026-07-06T01:49:49.327533Z","end":"2026-07-06T01:49:49.685826Z","steps":["trace[1987507350] 'agreement among raft nodes before linearized reading' (duration: 358.000134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:49.685892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:49.327508Z","time spent":"358.369016ms","remote":"127.0.0.1:40110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T01:49:55.103941Z","caller":"traceutil/trace.go:171","msg":"trace[434233498] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"309.707075ms","start":"2026-07-06T01:49:54.794208Z","end":"2026-07-06T01:49:55.103915Z","steps":["trace[434233498] 'process raft request' (duration: 309.037194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:55.104119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:54.794188Z","time spent":"309.840449ms","remote":"127.0.0.1:40204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:49:55.882931Z","caller":"traceutil/trace.go:171","msg":"trace[1588321105] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"168.512209ms","start":"2026-07-06T01:49:55.714364Z","end":"2026-07-06T01:49:55.882877Z","steps":["trace[1588321105] 'read index received' (duration: 167.26763ms)","trace[1588321105] 'applied index is now lower than readState.Index' (duration: 1.242879ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:49:55.883295Z","caller":"traceutil/trace.go:171","msg":"trace[1005453759] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"228.76239ms","start":"2026-07-06T01:49:55.654515Z","end":"2026-07-06T01:49:55.883277Z","steps":["trace[1005453759] 'process raft request' (duration: 227.316185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:55.883427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.976109ms","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-07-06T01:49:55.883552Z","caller":"traceutil/trace.go:171","msg":"trace[1698719186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"164.141194ms","start":"2026-07-06T01:49:55.719383Z","end":"2026-07-06T01:49:55.883524Z","steps":["trace[1698719186] 'agreement among raft nodes before linearized reading' (duration: 163.849405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:55.883536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.927247ms","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-07-06T01:49:55.883687Z","caller":"traceutil/trace.go:171","msg":"trace[180912548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"164.101713ms","start":"2026-07-06T01:49:55.719519Z","end":"2026-07-06T01:49:55.883621Z","steps":["trace[180912548] 'agreement among raft nodes before linearized reading' (duration: 163.812044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:55.883537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.141898ms","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-07-06T01:49:55.883794Z","caller":"traceutil/trace.go:171","msg":"trace[444399198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"169.45716ms","start":"2026-07-06T01:49:55.714304Z","end":"2026-07-06T01:49:55.883761Z","steps":["trace[444399198] 'agreement among raft nodes before linearized reading' (duration: 169.004476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:56.5834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.720659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:49:56.583672Z","caller":"traceutil/trace.go:171","msg":"trace[1288812375] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"694.320565ms","start":"2026-07-06T01:49:55.889322Z","end":"2026-07-06T01:49:56.583642Z","steps":["trace[1288812375] 'process raft request' (duration: 694.223842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:56.583783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:55.889314Z","time spent":"694.419067ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:49:56.583822Z","caller":"traceutil/trace.go:171","msg":"trace[919800030] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"694.51178ms","start":"2026-07-06T01:49:55.889197Z","end":"2026-07-06T01:49:56.583709Z","steps":["trace[919800030] 'process raft request' (duration: 378.351147ms)","trace[919800030] 'compare' (duration: 315.516002ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:49:56.584092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:55.888619Z","time spent":"695.307735ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:49:56.590186Z","caller":"traceutil/trace.go:171","msg":"trace[428603127] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"491.461184ms","start":"2026-07-06T01:49:56.098708Z","end":"2026-07-06T01:49:56.590169Z","steps":["trace[428603127] 'read index received' (duration: 169.010705ms)","trace[428603127] 'applied index is now lower than readState.Index' (duration: 322.449098ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:49:56.590289Z","caller":"traceutil/trace.go:171","msg":"trace[1233281308] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"646.706016ms","start":"2026-07-06T01:49:55.94354Z","end":"2026-07-06T01:49:56.590246Z","steps":["trace[1233281308] 'process raft request' (duration: 646.55897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:56.590353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.645349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-06T01:49:56.590387Z","caller":"traceutil/trace.go:171","msg":"trace[1294631313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"491.714552ms","start":"2026-07-06T01:49:56.09866Z","end":"2026-07-06T01:49:56.590375Z","steps":["trace[1294631313] 'agreement among raft nodes before linearized reading' (duration: 491.614739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:56.590377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.696264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T01:49:56.590412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:56.098639Z","time spent":"491.768344ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-06T01:49:56.590426Z","caller":"traceutil/trace.go:171","msg":"trace[1513066986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"258.786457ms","start":"2026-07-06T01:49:56.331626Z","end":"2026-07-06T01:49:56.590412Z","steps":["trace[1513066986] 'agreement among raft nodes before linearized reading' (duration: 258.677973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:56.590464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:55.94351Z","time spent":"646.866701ms","remote":"127.0.0.1:40446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T01:49:58.141362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.240237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:49:58.142404Z","caller":"traceutil/trace.go:171","msg":"trace[1123153261] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"813.028629ms","start":"2026-07-06T01:49:57.329353Z","end":"2026-07-06T01:49:58.142382Z","steps":["trace[1123153261] 'read index received' (duration: 333.583144ms)","trace[1123153261] 'applied index is now lower than readState.Index' (duration: 479.444305ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:49:58.142533Z","caller":"traceutil/trace.go:171","msg":"trace[999094894] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.444726218s","start":"2026-07-06T01:49:56.697792Z","end":"2026-07-06T01:49:58.142519Z","steps":["trace[999094894] 'process raft request' (duration: 965.18471ms)","trace[999094894] 'compare' (duration: 477.876816ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:49:58.142624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:56.697722Z","time spent":"1.444842531s","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:49:58.142658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.285657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:49:58.142758Z","caller":"traceutil/trace.go:171","msg":"trace[1399330569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"813.38912ms","start":"2026-07-06T01:49:57.329339Z","end":"2026-07-06T01:49:58.142728Z","steps":["trace[1399330569] 'agreement among raft nodes before linearized reading' (duration: 813.218075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:58.142795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:57.329288Z","time spent":"813.500283ms","remote":"127.0.0.1:40110","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-07-06T01:49:58.14289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.38602ms","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-07-06T01:49:58.142933Z","caller":"traceutil/trace.go:171","msg":"trace[1879023014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"225.489213ms","start":"2026-07-06T01:49:57.917429Z","end":"2026-07-06T01:49:58.142918Z","steps":["trace[1879023014] 'agreement among raft nodes before linearized reading' (duration: 225.298747ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:49:58.143002Z","caller":"traceutil/trace.go:171","msg":"trace[956099733] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.114739658s","start":"2026-07-06T01:49:57.02824Z","end":"2026-07-06T01:49:58.14298Z","steps":["trace[956099733] 'process raft request' (duration: 1.113465608s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:49:58.143135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:57.028198Z","time spent":"1.11484466s","remote":"127.0.0.1:40204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:49:58.924436Z","caller":"traceutil/trace.go:171","msg":"trace[1344194961] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"238.825181ms","start":"2026-07-06T01:49:58.685544Z","end":"2026-07-06T01:49:58.924369Z","steps":["trace[1344194961] 'process raft request' (duration: 237.070947ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:49:59.528389Z","caller":"traceutil/trace.go:171","msg":"trace[940897518] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"123.751901ms","start":"2026-07-06T01:49:59.404595Z","end":"2026-07-06T01:49:59.528347Z","steps":["trace[940897518] 'process raft request' (duration: 123.514394ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:00.503624Z","caller":"traceutil/trace.go:171","msg":"trace[56105529] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"584.345971ms","start":"2026-07-06T01:49:59.919251Z","end":"2026-07-06T01:50:00.503597Z","steps":["trace[56105529] 'process raft request' (duration: 584.107144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:00.503802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:49:59.919229Z","time spent":"584.484066ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T01:50:00.663446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.8271ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T01:50:00.664297Z","caller":"traceutil/trace.go:171","msg":"trace[1007415802] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"505.598956ms","start":"2026-07-06T01:50:00.157963Z","end":"2026-07-06T01:50:00.663562Z","steps":["trace[1007415802] 'read index received' (duration: 345.441081ms)","trace[1007415802] 'applied index is now lower than readState.Index' (duration: 160.155865ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:50:00.664513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.105551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:50:00.664582Z","caller":"traceutil/trace.go:171","msg":"trace[644881167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"332.214365ms","start":"2026-07-06T01:50:00.332354Z","end":"2026-07-06T01:50:00.664568Z","steps":["trace[644881167] 'agreement among raft nodes before linearized reading' (duration: 332.093861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:00.664636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:00.332335Z","time spent":"332.294337ms","remote":"127.0.0.1:40110","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-07-06T01:50:00.664675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.695979ms","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-07-06T01:50:00.664795Z","caller":"traceutil/trace.go:171","msg":"trace[2128665078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"506.873476ms","start":"2026-07-06T01:50:00.157893Z","end":"2026-07-06T01:50:00.664766Z","steps":["trace[2128665078] 'agreement among raft nodes before linearized reading' (duration: 505.851374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:00.664846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:00.157861Z","time spent":"506.974549ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T01:50:00.665113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.923799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8cmxz\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-07-06T01:50:00.665219Z","caller":"traceutil/trace.go:171","msg":"trace[714328874] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8cmxz; range_end:; response_count:1; response_revision:617; }","duration":"268.092703ms","start":"2026-07-06T01:50:00.397099Z","end":"2026-07-06T01:50:00.665192Z","steps":["trace[714328874] 'agreement among raft nodes before linearized reading' (duration: 267.753943ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:00.77367Z","caller":"traceutil/trace.go:171","msg":"trace[1711321723] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"101.849481ms","start":"2026-07-06T01:50:00.671791Z","end":"2026-07-06T01:50:00.77364Z","steps":["trace[1711321723] 'process raft request' (duration: 101.596853ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:00.773693Z","caller":"traceutil/trace.go:171","msg":"trace[796144460] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"101.563573ms","start":"2026-07-06T01:50:00.672104Z","end":"2026-07-06T01:50:00.773668Z","steps":["trace[796144460] 'process raft request' (duration: 101.45915ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:01.473744Z","caller":"traceutil/trace.go:171","msg":"trace[601914751] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"114.898447ms","start":"2026-07-06T01:50:01.358812Z","end":"2026-07-06T01:50:01.473711Z","steps":["trace[601914751] 'process raft request' (duration: 114.028081ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:01.536031Z","caller":"traceutil/trace.go:171","msg":"trace[1600430862] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"176.078855ms","start":"2026-07-06T01:50:01.35992Z","end":"2026-07-06T01:50:01.535999Z","steps":["trace[1600430862] 'process raft request' (duration: 175.823298ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:01.536006Z","caller":"traceutil/trace.go:171","msg":"trace[2113006106] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"129.982061ms","start":"2026-07-06T01:50:01.405993Z","end":"2026-07-06T01:50:01.535975Z","steps":["trace[2113006106] 'read index received' (duration: 66.855763ms)","trace[2113006106] 'applied index is now lower than readState.Index' (duration: 63.125128ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:50:01.536145Z","caller":"traceutil/trace.go:171","msg":"trace[632265929] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"121.435107ms","start":"2026-07-06T01:50:01.414652Z","end":"2026-07-06T01:50:01.536087Z","steps":["trace[632265929] 'process raft request' (duration: 121.263412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:01.536475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.259288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-dnx8p\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-07-06T01:50:01.536535Z","caller":"traceutil/trace.go:171","msg":"trace[483923907] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-dnx8p; range_end:; response_count:1; response_revision:630; }","duration":"130.545678ms","start":"2026-07-06T01:50:01.405976Z","end":"2026-07-06T01:50:01.536522Z","steps":["trace[483923907] 'agreement among raft nodes before linearized reading' (duration: 130.152865ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:01.66206Z","caller":"traceutil/trace.go:171","msg":"trace[161702237] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"117.861548ms","start":"2026-07-06T01:50:01.544166Z","end":"2026-07-06T01:50:01.662027Z","steps":["trace[161702237] 'process raft request' (duration: 117.571269ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:01.662245Z","caller":"traceutil/trace.go:171","msg":"trace[318745543] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"115.250407ms","start":"2026-07-06T01:50:01.546967Z","end":"2026-07-06T01:50:01.662217Z","steps":["trace[318745543] 'process raft request' (duration: 115.00612ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:01.697701Z","caller":"traceutil/trace.go:171","msg":"trace[1014695167] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"107.161009ms","start":"2026-07-06T01:50:01.590505Z","end":"2026-07-06T01:50:01.697666Z","steps":["trace[1014695167] 'process raft request' (duration: 106.906002ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:01.697744Z","caller":"traceutil/trace.go:171","msg":"trace[558310097] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"108.268343ms","start":"2026-07-06T01:50:01.589447Z","end":"2026-07-06T01:50:01.697715Z","steps":["trace[558310097] 'process raft request' (duration: 107.823289ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:03.37038Z","caller":"traceutil/trace.go:171","msg":"trace[1630557753] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"138.211717ms","start":"2026-07-06T01:50:03.232138Z","end":"2026-07-06T01:50:03.370349Z","steps":["trace[1630557753] 'process raft request' (duration: 137.857346ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:03.541746Z","caller":"traceutil/trace.go:171","msg":"trace[624046905] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"102.484384ms","start":"2026-07-06T01:50:03.439236Z","end":"2026-07-06T01:50:03.54172Z","steps":["trace[624046905] 'read index received' (duration: 30.575105ms)","trace[624046905] 'applied index is now lower than readState.Index' (duration: 71.908009ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:50:03.541903Z","caller":"traceutil/trace.go:171","msg":"trace[749258587] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"192.292762ms","start":"2026-07-06T01:50:03.349598Z","end":"2026-07-06T01:50:03.541891Z","steps":["trace[749258587] 'process raft request' (duration: 120.251079ms)","trace[749258587] 'compare' (duration: 71.679863ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:50:03.542402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.178367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8cmxz\" ","response":"range_response_count:1 size:18067"} {"level":"info","ts":"2026-07-06T01:50:03.542594Z","caller":"traceutil/trace.go:171","msg":"trace[1356006595] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8cmxz; range_end:; response_count:1; response_revision:675; }","duration":"103.30533ms","start":"2026-07-06T01:50:03.439172Z","end":"2026-07-06T01:50:03.542478Z","steps":["trace[1356006595] 'agreement among raft nodes before linearized reading' (duration: 102.766614ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:03.574375Z","caller":"traceutil/trace.go:171","msg":"trace[1504854344] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"110.328375ms","start":"2026-07-06T01:50:03.464018Z","end":"2026-07-06T01:50:03.574347Z","steps":["trace[1504854344] 'process raft request' (duration: 110.148349ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:03.574742Z","caller":"traceutil/trace.go:171","msg":"trace[2073518305] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"134.095951ms","start":"2026-07-06T01:50:03.440637Z","end":"2026-07-06T01:50:03.574733Z","steps":["trace[2073518305] 'process raft request' (duration: 133.239525ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:08.738532Z","caller":"traceutil/trace.go:171","msg":"trace[1661964130] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"152.494396ms","start":"2026-07-06T01:50:08.586013Z","end":"2026-07-06T01:50:08.738507Z","steps":["trace[1661964130] 'process raft request' (duration: 152.327231ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:08.88104Z","caller":"traceutil/trace.go:171","msg":"trace[2117821325] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"134.949033ms","start":"2026-07-06T01:50:08.746055Z","end":"2026-07-06T01:50:08.881004Z","steps":["trace[2117821325] 'process raft request' (duration: 101.764367ms)","trace[2117821325] 'compare' (duration: 33.039022ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:50:09.160008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.552309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:50:09.160215Z","caller":"traceutil/trace.go:171","msg":"trace[1310048460] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"410.085133ms","start":"2026-07-06T01:50:08.750091Z","end":"2026-07-06T01:50:09.160176Z","steps":["trace[1310048460] 'process raft request' (duration: 231.210194ms)","trace[1310048460] 'compare' (duration: 178.229609ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:50:09.160258Z","caller":"traceutil/trace.go:171","msg":"trace[1426552704] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"269.385524ms","start":"2026-07-06T01:50:08.890852Z","end":"2026-07-06T01:50:09.160238Z","steps":["trace[1426552704] 'process raft request' (duration: 269.308582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:09.160326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:08.750065Z","time spent":"410.223417ms","remote":"127.0.0.1:38422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2862,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T01:50:09.160313Z","caller":"traceutil/trace.go:171","msg":"trace[1665405542] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:742; }","duration":"397.302214ms","start":"2026-07-06T01:50:08.762983Z","end":"2026-07-06T01:50:09.160285Z","steps":["trace[1665405542] 'read index received' (duration: 84.847523ms)","trace[1665405542] 'applied index is now lower than readState.Index' (duration: 312.451831ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:50:09.160595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.610304ms","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-07-06T01:50:09.160654Z","caller":"traceutil/trace.go:171","msg":"trace[1975714760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"397.722287ms","start":"2026-07-06T01:50:08.762917Z","end":"2026-07-06T01:50:09.16064Z","steps":["trace[1975714760] 'agreement among raft nodes before linearized reading' (duration: 397.504011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:09.160679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.770504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-06T01:50:09.160711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:08.762878Z","time spent":"397.81583ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T01:50:09.160758Z","caller":"traceutil/trace.go:171","msg":"trace[1238990490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:720; }","duration":"349.904687ms","start":"2026-07-06T01:50:08.810837Z","end":"2026-07-06T01:50:09.160742Z","steps":["trace[1238990490] 'agreement among raft nodes before linearized reading' (duration: 349.68091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:09.160917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.185265ms","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-07-06T01:50:09.160873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:08.810797Z","time spent":"350.057411ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-06T01:50:09.160978Z","caller":"traceutil/trace.go:171","msg":"trace[487527110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:720; }","duration":"347.301217ms","start":"2026-07-06T01:50:08.813662Z","end":"2026-07-06T01:50:09.160963Z","steps":["trace[487527110] 'agreement among raft nodes before linearized reading' (duration: 347.142683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:09.161026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:08.81363Z","time spent":"347.38759ms","remote":"127.0.0.1:40344","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-07-06T01:50:09.161038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.747437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-06T01:50:09.161144Z","caller":"traceutil/trace.go:171","msg":"trace[1511255702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"305.916803ms","start":"2026-07-06T01:50:08.855199Z","end":"2026-07-06T01:50:09.161116Z","steps":["trace[1511255702] 'agreement among raft nodes before linearized reading' (duration: 305.565102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:09.161213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:08.855176Z","time spent":"306.019135ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T01:50:09.161465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.217471ms","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-07-06T01:50:09.161542Z","caller":"traceutil/trace.go:171","msg":"trace[592905247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"155.352166ms","start":"2026-07-06T01:50:09.006173Z","end":"2026-07-06T01:50:09.161525Z","steps":["trace[592905247] 'agreement among raft nodes before linearized reading' (duration: 154.521961ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:14.338127Z","caller":"traceutil/trace.go:171","msg":"trace[569668579] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"320.443442ms","start":"2026-07-06T01:50:14.017644Z","end":"2026-07-06T01:50:14.338087Z","steps":["trace[569668579] 'process raft request' (duration: 319.417712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:14.338373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:14.017609Z","time spent":"320.620537ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:50:14.845118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.302709ms","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-07-06T01:50:14.845242Z","caller":"traceutil/trace.go:171","msg":"trace[2078326647] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:757; }","duration":"347.512536ms","start":"2026-07-06T01:50:14.497701Z","end":"2026-07-06T01:50:14.845214Z","steps":["trace[2078326647] 'range keys from in-memory index tree' (duration: 347.024281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:14.846279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:14.497674Z","time spent":"348.552217ms","remote":"127.0.0.1:40128","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.144\" "} {"level":"info","ts":"2026-07-06T01:50:14.848942Z","caller":"traceutil/trace.go:171","msg":"trace[2070531545] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"320.929926ms","start":"2026-07-06T01:50:14.527995Z","end":"2026-07-06T01:50:14.848925Z","steps":["trace[2070531545] 'process raft request' (duration: 320.765842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:14.849094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:14.52796Z","time spent":"321.033879ms","remote":"127.0.0.1:40318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:50:17.649127Z","caller":"traceutil/trace.go:171","msg":"trace[1561496170] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"113.906899ms","start":"2026-07-06T01:50:17.535191Z","end":"2026-07-06T01:50:17.649098Z","steps":["trace[1561496170] 'process raft request' (duration: 113.731603ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:26.702584Z","caller":"traceutil/trace.go:171","msg":"trace[1633568465] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"185.030682ms","start":"2026-07-06T01:50:26.517505Z","end":"2026-07-06T01:50:26.702535Z","steps":["trace[1633568465] 'process raft request' (duration: 184.826216ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:26.891449Z","caller":"traceutil/trace.go:171","msg":"trace[626325306] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"178.301923ms","start":"2026-07-06T01:50:26.713114Z","end":"2026-07-06T01:50:26.891416Z","steps":["trace[626325306] 'process raft request' (duration: 178.226701ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:26.891492Z","caller":"traceutil/trace.go:171","msg":"trace[1178875571] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"183.637651ms","start":"2026-07-06T01:50:26.70783Z","end":"2026-07-06T01:50:26.891467Z","steps":["trace[1178875571] 'process raft request' (duration: 183.296331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:27.447147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.584361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:50:27.447345Z","caller":"traceutil/trace.go:171","msg":"trace[757252797] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"161.621124ms","start":"2026-07-06T01:50:27.28569Z","end":"2026-07-06T01:50:27.447311Z","steps":["trace[757252797] 'process raft request' (duration: 59.773407ms)","trace[757252797] 'compare' (duration: 101.311541ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:50:27.447404Z","caller":"traceutil/trace.go:171","msg":"trace[1329145526] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"137.344517ms","start":"2026-07-06T01:50:27.310041Z","end":"2026-07-06T01:50:27.447386Z","steps":["trace[1329145526] 'process raft request' (duration: 137.309456ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:27.447534Z","caller":"traceutil/trace.go:171","msg":"trace[1513350131] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"161.806349ms","start":"2026-07-06T01:50:27.285716Z","end":"2026-07-06T01:50:27.447522Z","steps":["trace[1513350131] 'process raft request' (duration: 161.504321ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:27.447652Z","caller":"traceutil/trace.go:171","msg":"trace[1701059273] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"161.236332ms","start":"2026-07-06T01:50:27.286406Z","end":"2026-07-06T01:50:27.447642Z","steps":["trace[1701059273] 'process raft request' (duration: 160.896902ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:27.447732Z","caller":"traceutil/trace.go:171","msg":"trace[1751039620] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:942; }","duration":"138.801751ms","start":"2026-07-06T01:50:27.30892Z","end":"2026-07-06T01:50:27.447722Z","steps":["trace[1751039620] 'read index received' (duration: 36.566841ms)","trace[1751039620] 'applied index is now lower than readState.Index' (duration: 102.23411ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:50:27.447842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.918774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:50:27.447867Z","caller":"traceutil/trace.go:171","msg":"trace[1497669009] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:916; }","duration":"138.943315ms","start":"2026-07-06T01:50:27.308911Z","end":"2026-07-06T01:50:27.447854Z","steps":["trace[1497669009] 'agreement among raft nodes before linearized reading' (duration: 138.899493ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:27.991033Z","caller":"traceutil/trace.go:171","msg":"trace[1144209731] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"162.528512ms","start":"2026-07-06T01:50:27.828487Z","end":"2026-07-06T01:50:27.991015Z","steps":["trace[1144209731] 'process raft request' (duration: 162.420488ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:28.163592Z","caller":"traceutil/trace.go:171","msg":"trace[1643061932] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"164.698269ms","start":"2026-07-06T01:50:27.998871Z","end":"2026-07-06T01:50:28.16357Z","steps":["trace[1643061932] 'process raft request' (duration: 163.989848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:38.592748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.427352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:50:38.592876Z","caller":"traceutil/trace.go:171","msg":"trace[2030418120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"266.607168ms","start":"2026-07-06T01:50:38.326239Z","end":"2026-07-06T01:50:38.592846Z","steps":["trace[2030418120] 'range keys from in-memory index tree' (duration: 266.32316ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:38.593459Z","caller":"traceutil/trace.go:171","msg":"trace[1919514000] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"144.390072ms","start":"2026-07-06T01:50:38.449045Z","end":"2026-07-06T01:50:38.593435Z","steps":["trace[1919514000] 'process raft request' (duration: 141.25219ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:38.900584Z","caller":"traceutil/trace.go:171","msg":"trace[221766318] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"198.659775ms","start":"2026-07-06T01:50:38.701877Z","end":"2026-07-06T01:50:38.900537Z","steps":["trace[221766318] 'process raft request' (duration: 196.008318ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:38.901502Z","caller":"traceutil/trace.go:171","msg":"trace[205410317] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"123.366169ms","start":"2026-07-06T01:50:38.778123Z","end":"2026-07-06T01:50:38.90149Z","steps":["trace[205410317] 'process raft request' (duration: 122.280707ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:50:40.094481Z","caller":"traceutil/trace.go:171","msg":"trace[34853125] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"206.700921ms","start":"2026-07-06T01:50:39.887735Z","end":"2026-07-06T01:50:40.094436Z","steps":["trace[34853125] 'process raft request' (duration: 194.901587ms)","trace[34853125] 'compare' (duration: 11.612029ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:50:48.160759Z","caller":"traceutil/trace.go:171","msg":"trace[754337039] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"145.447952ms","start":"2026-07-06T01:50:48.015263Z","end":"2026-07-06T01:50:48.160711Z","steps":["trace[754337039] 'process raft request' (duration: 145.314678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:48.681815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203422962643572,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T01:50:49.091034Z","caller":"traceutil/trace.go:171","msg":"trace[818462408] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"910.684971ms","start":"2026-07-06T01:50:48.180316Z","end":"2026-07-06T01:50:49.091001Z","steps":["trace[818462408] 'process raft request' (duration: 858.293018ms)","trace[818462408] 'compare' (duration: 51.994082ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:50:49.091198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:48.18029Z","time spent":"910.815446ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:50:49.093563Z","caller":"traceutil/trace.go:171","msg":"trace[1745917976] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1212; }","duration":"912.155424ms","start":"2026-07-06T01:50:48.181382Z","end":"2026-07-06T01:50:49.093537Z","steps":["trace[1745917976] 'read index received' (duration: 857.242999ms)","trace[1745917976] 'applied index is now lower than readState.Index' (duration: 54.908695ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:50:49.093721Z","caller":"traceutil/trace.go:171","msg":"trace[1697927811] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"883.776294ms","start":"2026-07-06T01:50:48.209895Z","end":"2026-07-06T01:50:49.093672Z","steps":["trace[1697927811] 'process raft request' (duration: 883.484256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:49.094012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.172315ms","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":"warn","ts":"2026-07-06T01:50:49.094017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:48.209857Z","time spent":"883.9809ms","remote":"127.0.0.1:40256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6385,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:50:49.094354Z","caller":"traceutil/trace.go:171","msg":"trace[1602190494] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1179; }","duration":"206.613267ms","start":"2026-07-06T01:50:48.887714Z","end":"2026-07-06T01:50:49.094327Z","steps":["trace[1602190494] 'agreement among raft nodes before linearized reading' (duration: 206.186844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:49.094475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.078611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-22vsr\" ","response":"range_response_count:1 size:4129"} {"level":"info","ts":"2026-07-06T01:50:49.094539Z","caller":"traceutil/trace.go:171","msg":"trace[348091391] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-22vsr; range_end:; response_count:1; response_revision:1179; }","duration":"913.161552ms","start":"2026-07-06T01:50:48.181359Z","end":"2026-07-06T01:50:49.09452Z","steps":["trace[348091391] 'agreement among raft nodes before linearized reading' (duration: 912.340609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:50:49.094614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:50:48.181348Z","time spent":"913.222665ms","remote":"127.0.0.1:40270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":4152,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-22vsr\" "} {"level":"warn","ts":"2026-07-06T01:51:04.927191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.569857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-229bk\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-07-06T01:51:04.927345Z","caller":"traceutil/trace.go:171","msg":"trace[632928909] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-229bk; range_end:; response_count:1; response_revision:1315; }","duration":"143.774553ms","start":"2026-07-06T01:51:04.783543Z","end":"2026-07-06T01:51:04.927317Z","steps":["trace[632928909] 'range keys from in-memory index tree' (duration: 143.383781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:12.339733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.253276ms","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-07-06T01:51:12.339839Z","caller":"traceutil/trace.go:171","msg":"trace[303714673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1441; }","duration":"340.430041ms","start":"2026-07-06T01:51:11.999386Z","end":"2026-07-06T01:51:12.339816Z","steps":["trace[303714673] 'range keys from in-memory index tree' (duration: 340.03614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:12.339887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:11.999363Z","time spent":"340.514074ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T01:51:12.339925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.576592ms","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-07-06T01:51:12.340011Z","caller":"traceutil/trace.go:171","msg":"trace[301011108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1441; }","duration":"342.745448ms","start":"2026-07-06T01:51:11.997246Z","end":"2026-07-06T01:51:12.339992Z","steps":["trace[301011108] 'range keys from in-memory index tree' (duration: 342.361527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:12.340051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:11.997226Z","time spent":"342.815069ms","remote":"127.0.0.1:40344","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":"info","ts":"2026-07-06T01:51:12.484542Z","caller":"traceutil/trace.go:171","msg":"trace[1915737431] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"140.854087ms","start":"2026-07-06T01:51:12.343665Z","end":"2026-07-06T01:51:12.484519Z","steps":["trace[1915737431] 'process raft request' (duration: 140.711443ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:51:12.496027Z","caller":"traceutil/trace.go:171","msg":"trace[1636483216] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"152.100096ms","start":"2026-07-06T01:51:12.3439Z","end":"2026-07-06T01:51:12.496Z","steps":["trace[1636483216] 'process raft request' (duration: 151.962652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:36.909517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.803717ms","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-07-06T01:51:36.909546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.301864ms","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-07-06T01:51:36.909584Z","caller":"traceutil/trace.go:171","msg":"trace[185202623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1850; }","duration":"291.88378ms","start":"2026-07-06T01:51:36.617684Z","end":"2026-07-06T01:51:36.909568Z","steps":["trace[185202623] 'range keys from in-memory index tree' (duration: 291.592342ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:51:36.909626Z","caller":"traceutil/trace.go:171","msg":"trace[1058545923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1850; }","duration":"291.428467ms","start":"2026-07-06T01:51:36.618178Z","end":"2026-07-06T01:51:36.909606Z","steps":["trace[1058545923] 'range keys from in-memory index tree' (duration: 291.134319ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:51:37.12617Z","caller":"traceutil/trace.go:171","msg":"trace[1381165754] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1922; }","duration":"461.089711ms","start":"2026-07-06T01:51:36.665052Z","end":"2026-07-06T01:51:37.126142Z","steps":["trace[1381165754] 'read index received' (duration: 460.947386ms)","trace[1381165754] 'applied index is now lower than readState.Index' (duration: 141.265µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:51:37.126278Z","caller":"traceutil/trace.go:171","msg":"trace[2019443360] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"488.069046ms","start":"2026-07-06T01:51:36.638172Z","end":"2026-07-06T01:51:37.126241Z","steps":["trace[2019443360] 'process raft request' (duration: 487.799878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:37.126453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.39615ms","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-07-06T01:51:37.126511Z","caller":"traceutil/trace.go:171","msg":"trace[460541110] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1851; }","duration":"461.523002ms","start":"2026-07-06T01:51:36.664963Z","end":"2026-07-06T01:51:37.126486Z","steps":["trace[460541110] 'agreement among raft nodes before linearized reading' (duration: 461.306517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:37.126547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:36.664922Z","time spent":"461.615955ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-06T01:51:37.126587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:36.638147Z","time spent":"488.203081ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:51:37.244284Z","caller":"traceutil/trace.go:171","msg":"trace[645067329] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"330.318015ms","start":"2026-07-06T01:51:36.913923Z","end":"2026-07-06T01:51:37.244241Z","steps":["trace[645067329] 'process raft request' (duration: 309.960754ms)","trace[645067329] 'compare' (duration: 20.079243ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:51:37.244433Z","caller":"traceutil/trace.go:171","msg":"trace[549226188] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1923; }","duration":"118.153789ms","start":"2026-07-06T01:51:37.12624Z","end":"2026-07-06T01:51:37.244394Z","steps":["trace[549226188] 'read index received' (duration: 97.648835ms)","trace[549226188] 'applied index is now lower than readState.Index' (duration: 20.500994ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:51:37.244635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.16715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:51:37.244683Z","caller":"traceutil/trace.go:171","msg":"trace[1938678585] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1853; }","duration":"477.261982ms","start":"2026-07-06T01:51:36.767409Z","end":"2026-07-06T01:51:37.244671Z","steps":["trace[1938678585] 'agreement among raft nodes before linearized reading' (duration: 477.109008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:37.244703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.747557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-80833b94-5ce2-4ade-aa84-627df148391e\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-06T01:51:37.244812Z","caller":"traceutil/trace.go:171","msg":"trace[246699555] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-80833b94-5ce2-4ade-aa84-627df148391e; range_end:; response_count:1; response_revision:1853; }","duration":"259.960092ms","start":"2026-07-06T01:51:36.984833Z","end":"2026-07-06T01:51:37.244793Z","steps":["trace[246699555] 'agreement among raft nodes before linearized reading' (duration: 259.574691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:37.244981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:36.913898Z","time spent":"330.564682ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:51:37.245159Z","caller":"traceutil/trace.go:171","msg":"trace[1531002070] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"112.649356ms","start":"2026-07-06T01:51:37.132493Z","end":"2026-07-06T01:51:37.245142Z","steps":["trace[1531002070] 'process raft request' (duration: 111.713649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:37.244905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.562839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-80833b94-5ce2-4ade-aa84-627df148391e\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-06T01:51:37.245362Z","caller":"traceutil/trace.go:171","msg":"trace[1424188813] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-80833b94-5ce2-4ade-aa84-627df148391e; range_end:; response_count:1; response_revision:1853; }","duration":"403.057043ms","start":"2026-07-06T01:51:36.842292Z","end":"2026-07-06T01:51:37.245349Z","steps":["trace[1424188813] 'agreement among raft nodes before linearized reading' (duration: 402.479007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:37.244738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:36.767344Z","time spent":"477.380896ms","remote":"127.0.0.1:40382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-06T01:51:37.245408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:36.842239Z","time spent":"403.154095ms","remote":"127.0.0.1:40270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-80833b94-5ce2-4ade-aa84-627df148391e\" "} {"level":"info","ts":"2026-07-06T01:51:37.244317Z","caller":"traceutil/trace.go:171","msg":"trace[1601658598] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"329.759389ms","start":"2026-07-06T01:51:36.914516Z","end":"2026-07-06T01:51:37.244275Z","steps":["trace[1601658598] 'process raft request' (duration: 329.623535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:37.245979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:36.914497Z","time spent":"331.438086ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:51:37.398798Z","caller":"traceutil/trace.go:171","msg":"trace[1996290529] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"146.329238ms","start":"2026-07-06T01:51:37.252439Z","end":"2026-07-06T01:51:37.398768Z","steps":["trace[1996290529] 'process raft request' (duration: 95.051722ms)","trace[1996290529] 'compare' (duration: 51.02617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:51:37.885154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.133069ms","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-07-06T01:51:37.885288Z","caller":"traceutil/trace.go:171","msg":"trace[1338465003] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"354.348956ms","start":"2026-07-06T01:51:37.530906Z","end":"2026-07-06T01:51:37.885255Z","steps":["trace[1338465003] 'range keys from in-memory index tree' (duration: 353.790569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:37.885343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:37.530876Z","time spent":"354.455319ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-06T01:51:37.966504Z","caller":"traceutil/trace.go:171","msg":"trace[573031543] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"115.456855ms","start":"2026-07-06T01:51:37.851Z","end":"2026-07-06T01:51:37.966457Z","steps":["trace[573031543] 'process raft request' (duration: 115.230668ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:51:50.20762Z","caller":"traceutil/trace.go:171","msg":"trace[447048856] linearizableReadLoop","detail":"{readStateIndex:2034; appliedIndex:2033; }","duration":"217.483642ms","start":"2026-07-06T01:51:49.99011Z","end":"2026-07-06T01:51:50.207594Z","steps":["trace[447048856] 'read index received' (duration: 217.164343ms)","trace[447048856] 'applied index is now lower than readState.Index' (duration: 317.319µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:51:50.207689Z","caller":"traceutil/trace.go:171","msg":"trace[1546892349] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"228.050676ms","start":"2026-07-06T01:51:49.979609Z","end":"2026-07-06T01:51:50.20766Z","steps":["trace[1546892349] 'process raft request' (duration: 227.793918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:50.208005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.125287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-06T01:51:50.208074Z","caller":"traceutil/trace.go:171","msg":"trace[1672618891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1959; }","duration":"139.308973ms","start":"2026-07-06T01:51:50.068749Z","end":"2026-07-06T01:51:50.208058Z","steps":["trace[1672618891] 'agreement among raft nodes before linearized reading' (duration: 139.024925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:50.208069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.552164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-06T01:51:50.208069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.743569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-06T01:51:50.208155Z","caller":"traceutil/trace.go:171","msg":"trace[771522366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1959; }","duration":"104.812062ms","start":"2026-07-06T01:51:50.103322Z","end":"2026-07-06T01:51:50.208134Z","steps":["trace[771522366] 'agreement among raft nodes before linearized reading' (duration: 104.454501ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:51:50.208159Z","caller":"traceutil/trace.go:171","msg":"trace[1482439139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1959; }","duration":"104.891964ms","start":"2026-07-06T01:51:50.103251Z","end":"2026-07-06T01:51:50.208143Z","steps":["trace[1482439139] 'agreement among raft nodes before linearized reading' (duration: 104.608685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:50.208215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.078219ms","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-07-06T01:51:50.20832Z","caller":"traceutil/trace.go:171","msg":"trace[803490189] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1959; }","duration":"218.248454ms","start":"2026-07-06T01:51:49.990044Z","end":"2026-07-06T01:51:50.208293Z","steps":["trace[803490189] 'agreement among raft nodes before linearized reading' (duration: 217.719409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:50.635743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.574838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:51:50.636002Z","caller":"traceutil/trace.go:171","msg":"trace[717291514] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"422.6418ms","start":"2026-07-06T01:51:50.213341Z","end":"2026-07-06T01:51:50.635982Z","steps":["trace[717291514] 'process raft request' (duration: 422.574549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:50.636069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:50.213331Z","time spent":"422.710442ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:51:50.63627Z","caller":"traceutil/trace.go:171","msg":"trace[1630394384] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"423.193034ms","start":"2026-07-06T01:51:50.21305Z","end":"2026-07-06T01:51:50.636243Z","steps":["trace[1630394384] 'process raft request' (duration: 422.811823ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:51:50.636259Z","caller":"traceutil/trace.go:171","msg":"trace[1650385017] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"428.513183ms","start":"2026-07-06T01:51:50.207694Z","end":"2026-07-06T01:51:50.636207Z","steps":["trace[1650385017] 'process raft request' (duration: 104.320558ms)","trace[1650385017] 'compare' (duration: 323.429374ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:51:50.636406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.87082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-06T01:51:50.63646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:50.213015Z","time spent":"423.327098ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:51:50.636476Z","caller":"traceutil/trace.go:171","msg":"trace[1019660684] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1963; }","duration":"167.033385ms","start":"2026-07-06T01:51:50.46943Z","end":"2026-07-06T01:51:50.636463Z","steps":["trace[1019660684] 'agreement among raft nodes before linearized reading' (duration: 166.880061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:50.636493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:50.20766Z","time spent":"428.703259ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:51:50.63625Z","caller":"traceutil/trace.go:171","msg":"trace[1784117502] linearizableReadLoop","detail":"{readStateIndex:2038; appliedIndex:2034; }","duration":"166.701066ms","start":"2026-07-06T01:51:50.469503Z","end":"2026-07-06T01:51:50.636204Z","steps":["trace[1784117502] 'read index received' (duration: 88.026054ms)","trace[1784117502] 'applied index is now lower than readState.Index' (duration: 78.671832ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:51:50.63679Z","caller":"traceutil/trace.go:171","msg":"trace[822956213] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"423.179365ms","start":"2026-07-06T01:51:50.213595Z","end":"2026-07-06T01:51:50.636774Z","steps":["trace[822956213] 'process raft request' (duration: 422.354373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:50.636906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:50.213583Z","time spent":"423.275758ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:51:50.637239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.08959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T01:51:50.637339Z","caller":"traceutil/trace.go:171","msg":"trace[109001959] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1963; }","duration":"109.252026ms","start":"2026-07-06T01:51:50.528045Z","end":"2026-07-06T01:51:50.637297Z","steps":["trace[109001959] 'agreement among raft nodes before linearized reading' (duration: 108.785563ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:51:52.817925Z","caller":"traceutil/trace.go:171","msg":"trace[671970603] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"168.534234ms","start":"2026-07-06T01:51:52.649368Z","end":"2026-07-06T01:51:52.817903Z","steps":["trace[671970603] 'process raft request' (duration: 168.493983ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:51:52.818229Z","caller":"traceutil/trace.go:171","msg":"trace[1895639622] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"170.230871ms","start":"2026-07-06T01:51:52.647987Z","end":"2026-07-06T01:51:52.818218Z","steps":["trace[1895639622] 'process raft request' (duration: 169.84279ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:51:52.818243Z","caller":"traceutil/trace.go:171","msg":"trace[1352073425] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"170.489708ms","start":"2026-07-06T01:51:52.647573Z","end":"2026-07-06T01:51:52.818063Z","steps":["trace[1352073425] 'process raft request' (duration: 169.929943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:53.866947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.156089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T01:51:53.867052Z","caller":"traceutil/trace.go:171","msg":"trace[1195822688] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1984; }","duration":"508.328474ms","start":"2026-07-06T01:51:53.358696Z","end":"2026-07-06T01:51:53.867024Z","steps":["trace[1195822688] 'count revisions from in-memory index tree' (duration: 508.049606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:53.867122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:53.358672Z","time spent":"508.433456ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":3,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-06T01:51:53.867145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.505647ms","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-07-06T01:51:53.867226Z","caller":"traceutil/trace.go:171","msg":"trace[739831034] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1984; }","duration":"453.684002ms","start":"2026-07-06T01:51:53.41352Z","end":"2026-07-06T01:51:53.867204Z","steps":["trace[739831034] 'range keys from in-memory index tree' (duration: 453.239441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:53.867275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:53.413492Z","time spent":"453.773565ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-06T01:51:53.867272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.819767ms","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-07-06T01:51:53.86735Z","caller":"traceutil/trace.go:171","msg":"trace[262489343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1984; }","duration":"457.939731ms","start":"2026-07-06T01:51:53.409391Z","end":"2026-07-06T01:51:53.867331Z","steps":["trace[262489343] 'range keys from in-memory index tree' (duration: 457.641053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:53.867383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:53.409373Z","time spent":"458.002012ms","remote":"127.0.0.1:40344","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-07-06T01:51:53.867407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.595461ms","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-07-06T01:51:53.867505Z","caller":"traceutil/trace.go:171","msg":"trace[1647443177] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1984; }","duration":"638.749785ms","start":"2026-07-06T01:51:53.228729Z","end":"2026-07-06T01:51:53.867479Z","steps":["trace[1647443177] 'range keys from in-memory index tree' (duration: 638.337153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:53.867554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:53.228706Z","time spent":"638.837187ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-06T01:51:53.867532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.723546ms","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-07-06T01:51:53.867614Z","caller":"traceutil/trace.go:171","msg":"trace[66465640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1984; }","duration":"456.83159ms","start":"2026-07-06T01:51:53.410762Z","end":"2026-07-06T01:51:53.867593Z","steps":["trace[66465640] 'range keys from in-memory index tree' (duration: 456.49989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:53.867654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:53.410755Z","time spent":"456.890912ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T01:51:53.870299Z","caller":"traceutil/trace.go:171","msg":"trace[360499268] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"410.721684ms","start":"2026-07-06T01:51:53.459559Z","end":"2026-07-06T01:51:53.870281Z","steps":["trace[360499268] 'process raft request' (duration: 410.56754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:51:53.870439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:51:53.459534Z","time spent":"410.820698ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:52:00.999095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.944856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-06T01:52:00.999382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.681285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-06T01:52:00.999551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.425581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T01:52:00.999537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.985619ms","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-07-06T01:52:00.999583Z","caller":"traceutil/trace.go:171","msg":"trace[1218245542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2022; }","duration":"150.073599ms","start":"2026-07-06T01:52:00.849082Z","end":"2026-07-06T01:52:00.999155Z","steps":["trace[1218245542] 'range keys from in-memory index tree' (duration: 149.770101ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:52:00.99959Z","caller":"traceutil/trace.go:171","msg":"trace[480898568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2022; }","duration":"104.487582ms","start":"2026-07-06T01:52:00.895086Z","end":"2026-07-06T01:52:00.999574Z","steps":["trace[480898568] 'range keys from in-memory index tree' (duration: 104.334948ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:52:00.999622Z","caller":"traceutil/trace.go:171","msg":"trace[227431300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2022; }","duration":"136.121322ms","start":"2026-07-06T01:52:00.863481Z","end":"2026-07-06T01:52:00.999602Z","steps":["trace[227431300] 'range keys from in-memory index tree' (duration: 135.776783ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:52:00.999645Z","caller":"traceutil/trace.go:171","msg":"trace[174698439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2022; }","duration":"141.805791ms","start":"2026-07-06T01:52:00.857628Z","end":"2026-07-06T01:52:00.999434Z","steps":["trace[174698439] 'range keys from in-memory index tree' (duration: 141.537242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:52:18.262165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.656059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-06T01:52:18.26226Z","caller":"traceutil/trace.go:171","msg":"trace[1514912821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2120; }","duration":"157.785012ms","start":"2026-07-06T01:52:18.104453Z","end":"2026-07-06T01:52:18.262238Z","steps":["trace[1514912821] 'range keys from in-memory index tree' (duration: 157.509025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:52:18.2624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.128126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-06T01:52:18.262454Z","caller":"traceutil/trace.go:171","msg":"trace[925331978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2120; }","duration":"165.215398ms","start":"2026-07-06T01:52:18.097219Z","end":"2026-07-06T01:52:18.262434Z","steps":["trace[925331978] 'range keys from in-memory index tree' (duration: 164.90611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:52:18.262638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.912196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-06T01:52:18.262737Z","caller":"traceutil/trace.go:171","msg":"trace[1977273978] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2120; }","duration":"154.024549ms","start":"2026-07-06T01:52:18.108699Z","end":"2026-07-06T01:52:18.262724Z","steps":["trace[1977273978] 'range keys from in-memory index tree' (duration: 153.365271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:52:18.262825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.110948ms","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-07-06T01:52:18.262881Z","caller":"traceutil/trace.go:171","msg":"trace[1510289345] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2120; }","duration":"100.223951ms","start":"2026-07-06T01:52:18.162639Z","end":"2026-07-06T01:52:18.262863Z","steps":["trace[1510289345] 'range keys from in-memory index tree' (duration: 99.931163ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:52:18.428913Z","caller":"traceutil/trace.go:171","msg":"trace[2182629] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"162.188654ms","start":"2026-07-06T01:52:18.266701Z","end":"2026-07-06T01:52:18.42889Z","steps":["trace[2182629] 'process raft request' (duration: 162.02936ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:52:18.784347Z","caller":"traceutil/trace.go:171","msg":"trace[1235188331] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"517.113419ms","start":"2026-07-06T01:52:18.267206Z","end":"2026-07-06T01:52:18.784319Z","steps":["trace[1235188331] 'process raft request' (duration: 516.910264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:52:18.784515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:52:18.267195Z","time spent":"517.225962ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:52:18.784573Z","caller":"traceutil/trace.go:171","msg":"trace[587525760] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2200; }","duration":"455.277878ms","start":"2026-07-06T01:52:18.329269Z","end":"2026-07-06T01:52:18.784546Z","steps":["trace[587525760] 'read index received' (duration: 99.511011ms)","trace[587525760] 'applied index is now lower than readState.Index' (duration: 355.765757ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:52:18.784969Z","caller":"traceutil/trace.go:171","msg":"trace[1927314375] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"517.057748ms","start":"2026-07-06T01:52:18.267897Z","end":"2026-07-06T01:52:18.784955Z","steps":["trace[1927314375] 'process raft request' (duration: 516.367399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:52:18.785065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:52:18.267886Z","time spent":"517.122179ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:52:18.785242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.732621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:52:18.785905Z","caller":"traceutil/trace.go:171","msg":"trace[687594339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2123; }","duration":"456.667957ms","start":"2026-07-06T01:52:18.329212Z","end":"2026-07-06T01:52:18.78588Z","steps":["trace[687594339] 'agreement among raft nodes before linearized reading' (duration: 455.682951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:52:18.785984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:52:18.329191Z","time spent":"456.762669ms","remote":"127.0.0.1:40110","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-07-06T01:52:19.138572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.994968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:52:19.138771Z","caller":"traceutil/trace.go:171","msg":"trace[1457020369] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"345.689348ms","start":"2026-07-06T01:52:18.793051Z","end":"2026-07-06T01:52:19.13874Z","steps":["trace[1457020369] 'process raft request' (duration: 89.425573ms)","trace[1457020369] 'compare' (duration: 255.880915ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:52:19.138986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:52:18.793038Z","time spent":"345.827482ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:53:01.12934Z","caller":"traceutil/trace.go:171","msg":"trace[2124862191] linearizableReadLoop","detail":"{readStateIndex:2499; appliedIndex:2497; }","duration":"123.072809ms","start":"2026-07-06T01:53:01.006248Z","end":"2026-07-06T01:53:01.129321Z","steps":["trace[2124862191] 'read index received' (duration: 63.687989ms)","trace[2124862191] 'applied index is now lower than readState.Index' (duration: 59.38417ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:53:01.129459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.189012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:53:01.129482Z","caller":"traceutil/trace.go:171","msg":"trace[1363304506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2408; }","duration":"123.241163ms","start":"2026-07-06T01:53:01.006231Z","end":"2026-07-06T01:53:01.129472Z","steps":["trace[1363304506] 'agreement among raft nodes before linearized reading' (duration: 123.170151ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:53:01.129459Z","caller":"traceutil/trace.go:171","msg":"trace[1237550044] transaction","detail":"{read_only:false; response_revision:2408; number_of_response:1; }","duration":"132.115947ms","start":"2026-07-06T01:53:00.997317Z","end":"2026-07-06T01:53:01.129433Z","steps":["trace[1237550044] 'process raft request' (duration: 131.213293ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:53:07.520825Z","caller":"traceutil/trace.go:171","msg":"trace[35887800] linearizableReadLoop","detail":"{readStateIndex:2581; appliedIndex:2580; }","duration":"339.165325ms","start":"2026-07-06T01:53:07.181637Z","end":"2026-07-06T01:53:07.520803Z","steps":["trace[35887800] 'read index received' (duration: 339.070043ms)","trace[35887800] 'applied index is now lower than readState.Index' (duration: 94.132µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:53:07.520876Z","caller":"traceutil/trace.go:171","msg":"trace[915816264] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"412.265432ms","start":"2026-07-06T01:53:07.108587Z","end":"2026-07-06T01:53:07.520852Z","steps":["trace[915816264] 'process raft request' (duration: 412.074577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:07.521006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.35188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-07-06T01:53:07.521012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:07.108574Z","time spent":"412.358654ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:53:07.521061Z","caller":"traceutil/trace.go:171","msg":"trace[1716580745] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2489; }","duration":"339.438683ms","start":"2026-07-06T01:53:07.181608Z","end":"2026-07-06T01:53:07.521047Z","steps":["trace[1716580745] 'agreement among raft nodes before linearized reading' (duration: 339.309069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:07.521086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:07.181595Z","time spent":"339.487295ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-06T01:53:07.536345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.524203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-06T01:53:07.536417Z","caller":"traceutil/trace.go:171","msg":"trace[406866738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2490; }","duration":"106.649906ms","start":"2026-07-06T01:53:07.429746Z","end":"2026-07-06T01:53:07.536396Z","steps":["trace[406866738] 'agreement among raft nodes before linearized reading' (duration: 106.460331ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:53:07.536735Z","caller":"traceutil/trace.go:171","msg":"trace[1482544568] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"199.991377ms","start":"2026-07-06T01:53:07.33673Z","end":"2026-07-06T01:53:07.536721Z","steps":["trace[1482544568] 'process raft request' (duration: 199.38649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:41.816014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.941463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:53:41.816095Z","caller":"traceutil/trace.go:171","msg":"trace[1195195723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2677; }","duration":"629.272381ms","start":"2026-07-06T01:53:41.186803Z","end":"2026-07-06T01:53:41.816076Z","steps":["trace[1195195723] 'range keys from in-memory index tree' (duration: 628.818831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:41.816155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:41.186774Z","time spent":"629.363942ms","remote":"127.0.0.1:40110","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-07-06T01:53:41.817278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.524157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T01:53:41.817737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.172404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:53:41.81739Z","caller":"traceutil/trace.go:171","msg":"trace[1971172759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2677; }","duration":"491.691912ms","start":"2026-07-06T01:53:41.325674Z","end":"2026-07-06T01:53:41.817365Z","steps":["trace[1971172759] 'range keys from in-memory index tree' (duration: 491.449736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:41.818161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:41.325656Z","time spent":"492.483669ms","remote":"127.0.0.1:40122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T01:53:41.817849Z","caller":"traceutil/trace.go:171","msg":"trace[1370758994] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"124.925367ms","start":"2026-07-06T01:53:41.692905Z","end":"2026-07-06T01:53:41.81783Z","steps":["trace[1370758994] 'process raft request' (duration: 22.578279ms)","trace[1370758994] 'compare' (duration: 100.307883ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:53:42.541601Z","caller":"traceutil/trace.go:171","msg":"trace[1034665001] linearizableReadLoop","detail":"{readStateIndex:2779; appliedIndex:2777; }","duration":"832.688817ms","start":"2026-07-06T01:53:41.708888Z","end":"2026-07-06T01:53:42.541577Z","steps":["trace[1034665001] 'read index received' (duration: 6.47672ms)","trace[1034665001] 'applied index is now lower than readState.Index' (duration: 826.210927ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:53:42.541919Z","caller":"traceutil/trace.go:171","msg":"trace[718611631] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"838.047664ms","start":"2026-07-06T01:53:41.703838Z","end":"2026-07-06T01:53:42.541886Z","steps":["trace[718611631] 'process raft request' (duration: 837.616845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:42.542131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:41.7038Z","time spent":"838.244709ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:53:42.542904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.972825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T01:53:42.543005Z","caller":"traceutil/trace.go:171","msg":"trace[717804565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2679; }","duration":"834.136679ms","start":"2026-07-06T01:53:41.708846Z","end":"2026-07-06T01:53:42.542983Z","steps":["trace[717804565] 'agreement among raft nodes before linearized reading' (duration: 833.813652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:42.543169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:41.708828Z","time spent":"834.331292ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T01:53:42.550587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.110676ms","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-07-06T01:53:42.550664Z","caller":"traceutil/trace.go:171","msg":"trace[1865666547] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2680; }","duration":"724.264669ms","start":"2026-07-06T01:53:41.826381Z","end":"2026-07-06T01:53:42.550645Z","steps":["trace[1865666547] 'agreement among raft nodes before linearized reading' (duration: 724.074535ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:53:42.550816Z","caller":"traceutil/trace.go:171","msg":"trace[546537907] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"190.128224ms","start":"2026-07-06T01:53:42.360675Z","end":"2026-07-06T01:53:42.550804Z","steps":["trace[546537907] 'process raft request' (duration: 189.597983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:42.550837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:41.82636Z","time spent":"724.462964ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-06T01:53:42.550589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.032799ms","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-07-06T01:53:42.551007Z","caller":"traceutil/trace.go:171","msg":"trace[916110911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2680; }","duration":"841.541831ms","start":"2026-07-06T01:53:41.709456Z","end":"2026-07-06T01:53:42.550998Z","steps":["trace[916110911] 'agreement among raft nodes before linearized reading' (duration: 840.952798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:42.55115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:41.709441Z","time spent":"841.699334ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T01:53:42.550772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.654495ms","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":"warn","ts":"2026-07-06T01:53:42.551171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.909741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:53:42.551227Z","caller":"traceutil/trace.go:171","msg":"trace[1766378196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2680; }","duration":"724.154758ms","start":"2026-07-06T01:53:41.827061Z","end":"2026-07-06T01:53:42.551216Z","steps":["trace[1766378196] 'agreement among raft nodes before linearized reading' (duration: 723.665297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:42.551281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:41.82704Z","time spent":"724.230619ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-06T01:53:42.550686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.435049ms","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-07-06T01:53:42.551306Z","caller":"traceutil/trace.go:171","msg":"trace[2074263304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2680; }","duration":"225.065565ms","start":"2026-07-06T01:53:42.326198Z","end":"2026-07-06T01:53:42.551264Z","steps":["trace[2074263304] 'agreement among raft nodes before linearized reading' (duration: 224.870961ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:53:42.55138Z","caller":"traceutil/trace.go:171","msg":"trace[538167430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2680; }","duration":"729.137934ms","start":"2026-07-06T01:53:41.822218Z","end":"2026-07-06T01:53:42.551356Z","steps":["trace[538167430] 'agreement among raft nodes before linearized reading' (duration: 728.411389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:42.551413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:41.822199Z","time spent":"729.207785ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T01:53:42.551616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.502955ms","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-07-06T01:53:42.55165Z","caller":"traceutil/trace.go:171","msg":"trace[830239024] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2680; }","duration":"719.550706ms","start":"2026-07-06T01:53:41.83209Z","end":"2026-07-06T01:53:42.551641Z","steps":["trace[830239024] 'agreement among raft nodes before linearized reading' (duration: 719.451534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:53:42.551684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:53:41.832084Z","time spent":"719.594198ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-07-06T01:54:02.867578Z","caller":"traceutil/trace.go:171","msg":"trace[2043876851] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"104.42482ms","start":"2026-07-06T01:54:02.763121Z","end":"2026-07-06T01:54:02.867546Z","steps":["trace[2043876851] 'process raft request' (duration: 58.386559ms)","trace[2043876851] 'compare' (duration: 45.924408ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:56:13.291008Z","caller":"traceutil/trace.go:171","msg":"trace[1285920043] transaction","detail":"{read_only:false; response_revision:3570; number_of_response:1; }","duration":"138.514686ms","start":"2026-07-06T01:56:13.152469Z","end":"2026-07-06T01:56:13.290984Z","steps":["trace[1285920043] 'process raft request' (duration: 138.360303ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:19.793052Z","caller":"traceutil/trace.go:171","msg":"trace[1250129458] transaction","detail":"{read_only:false; response_revision:3610; number_of_response:1; }","duration":"106.880929ms","start":"2026-07-06T01:56:19.686149Z","end":"2026-07-06T01:56:19.79303Z","steps":["trace[1250129458] 'process raft request' (duration: 106.731746ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:19.811729Z","caller":"traceutil/trace.go:171","msg":"trace[1000045529] transaction","detail":"{read_only:false; response_revision:3611; number_of_response:1; }","duration":"125.509168ms","start":"2026-07-06T01:56:19.686199Z","end":"2026-07-06T01:56:19.811708Z","steps":["trace[1000045529] 'process raft request' (duration: 125.400296ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:22.595193Z","caller":"traceutil/trace.go:171","msg":"trace[951925040] transaction","detail":"{read_only:false; response_revision:3622; number_of_response:1; }","duration":"215.46394ms","start":"2026-07-06T01:56:22.379696Z","end":"2026-07-06T01:56:22.59516Z","steps":["trace[951925040] 'process raft request' (duration: 215.271004ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:22.598042Z","caller":"traceutil/trace.go:171","msg":"trace[1620110068] transaction","detail":"{read_only:false; response_revision:3623; number_of_response:1; }","duration":"103.107568ms","start":"2026-07-06T01:56:22.494916Z","end":"2026-07-06T01:56:22.598024Z","steps":["trace[1620110068] 'process raft request' (duration: 102.964535ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:22.906357Z","caller":"traceutil/trace.go:171","msg":"trace[1997188888] transaction","detail":"{read_only:false; response_revision:3635; number_of_response:1; }","duration":"132.12965ms","start":"2026-07-06T01:56:22.774202Z","end":"2026-07-06T01:56:22.906332Z","steps":["trace[1997188888] 'process raft request' (duration: 46.158173ms)","trace[1997188888] 'compare' (duration: 85.734841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:56:23.22061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.012721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:56:23.220925Z","caller":"traceutil/trace.go:171","msg":"trace[1718483882] linearizableReadLoop","detail":"{readStateIndex:3780; appliedIndex:3773; }","duration":"138.588584ms","start":"2026-07-06T01:56:23.082321Z","end":"2026-07-06T01:56:23.22091Z","steps":["trace[1718483882] 'read index received' (duration: 138.173003ms)","trace[1718483882] 'applied index is now lower than readState.Index' (duration: 414.691µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:56:23.221036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.710657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T01:56:23.221063Z","caller":"traceutil/trace.go:171","msg":"trace[1198180137] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:3643; }","duration":"138.791489ms","start":"2026-07-06T01:56:23.082263Z","end":"2026-07-06T01:56:23.221055Z","steps":["trace[1198180137] 'agreement among raft nodes before linearized reading' (duration: 138.691156ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:23.221048Z","caller":"traceutil/trace.go:171","msg":"trace[903015267] transaction","detail":"{read_only:false; response_revision:3641; number_of_response:1; }","duration":"239.497458ms","start":"2026-07-06T01:56:22.981518Z","end":"2026-07-06T01:56:23.221016Z","steps":["trace[903015267] 'process raft request' (duration: 239.303292ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:23.221311Z","caller":"traceutil/trace.go:171","msg":"trace[358184934] transaction","detail":"{read_only:false; response_revision:3642; number_of_response:1; }","duration":"239.444746ms","start":"2026-07-06T01:56:22.981854Z","end":"2026-07-06T01:56:23.221299Z","steps":["trace[358184934] 'process raft request' (duration: 238.986335ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:23.221316Z","caller":"traceutil/trace.go:171","msg":"trace[1802200967] transaction","detail":"{read_only:false; response_revision:3638; number_of_response:1; }","duration":"240.864501ms","start":"2026-07-06T01:56:22.980435Z","end":"2026-07-06T01:56:23.2213Z","steps":["trace[1802200967] 'process raft request' (duration: 240.283617ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:23.220883Z","caller":"traceutil/trace.go:171","msg":"trace[1662663929] transaction","detail":"{read_only:false; response_revision:3637; number_of_response:1; }","duration":"240.646075ms","start":"2026-07-06T01:56:22.980179Z","end":"2026-07-06T01:56:23.220825Z","steps":["trace[1662663929] 'process raft request' (duration: 96.343277ms)","trace[1662663929] 'compare' (duration: 143.778286ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:56:23.221435Z","caller":"traceutil/trace.go:171","msg":"trace[1349710750] transaction","detail":"{read_only:false; response_revision:3639; number_of_response:1; }","duration":"240.388789ms","start":"2026-07-06T01:56:22.981034Z","end":"2026-07-06T01:56:23.221423Z","steps":["trace[1349710750] 'process raft request' (duration: 239.745613ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:23.221646Z","caller":"traceutil/trace.go:171","msg":"trace[9102618] transaction","detail":"{read_only:false; response_revision:3640; number_of_response:1; }","duration":"240.177474ms","start":"2026-07-06T01:56:22.981458Z","end":"2026-07-06T01:56:23.221635Z","steps":["trace[9102618] 'process raft request' (duration: 239.340693ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:23.224006Z","caller":"traceutil/trace.go:171","msg":"trace[159419502] transaction","detail":"{read_only:false; response_revision:3643; number_of_response:1; }","duration":"238.938693ms","start":"2026-07-06T01:56:22.985044Z","end":"2026-07-06T01:56:23.223983Z","steps":["trace[159419502] 'process raft request' (duration: 235.815244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:56:27.660171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.617304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T01:56:27.66028Z","caller":"traceutil/trace.go:171","msg":"trace[2111955271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3708; }","duration":"118.741378ms","start":"2026-07-06T01:56:27.541514Z","end":"2026-07-06T01:56:27.660256Z","steps":["trace[2111955271] 'range keys from in-memory index tree' (duration: 118.39697ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:27.763645Z","caller":"traceutil/trace.go:171","msg":"trace[1965460988] transaction","detail":"{read_only:false; response_revision:3710; number_of_response:1; }","duration":"128.906955ms","start":"2026-07-06T01:56:27.634715Z","end":"2026-07-06T01:56:27.763621Z","steps":["trace[1965460988] 'process raft request' (duration: 128.566537ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:32.0626Z","caller":"traceutil/trace.go:171","msg":"trace[1919748240] transaction","detail":"{read_only:false; response_revision:3736; number_of_response:1; }","duration":"190.173411ms","start":"2026-07-06T01:56:31.872401Z","end":"2026-07-06T01:56:32.062575Z","steps":["trace[1919748240] 'process raft request' (duration: 190.094649ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:32.062635Z","caller":"traceutil/trace.go:171","msg":"trace[822317661] linearizableReadLoop","detail":"{readStateIndex:3876; appliedIndex:3873; }","duration":"154.437258ms","start":"2026-07-06T01:56:31.908187Z","end":"2026-07-06T01:56:32.062624Z","steps":["trace[822317661] 'read index received' (duration: 6.190227ms)","trace[822317661] 'applied index is now lower than readState.Index' (duration: 148.24498ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:56:32.062874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.668763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-goldpinger\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:56:32.062931Z","caller":"traceutil/trace.go:171","msg":"trace[1776043831] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-goldpinger; range_end:; response_count:0; response_revision:3736; }","duration":"154.780625ms","start":"2026-07-06T01:56:31.908133Z","end":"2026-07-06T01:56:32.062913Z","steps":["trace[1776043831] 'agreement among raft nodes before linearized reading' (duration: 154.681883ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:32.06259Z","caller":"traceutil/trace.go:171","msg":"trace[1393410662] transaction","detail":"{read_only:false; response_revision:3735; number_of_response:1; }","duration":"192.1065ms","start":"2026-07-06T01:56:31.87045Z","end":"2026-07-06T01:56:32.062557Z","steps":["trace[1393410662] 'process raft request' (duration: 150.470135ms)","trace[1393410662] 'compare' (duration: 41.477571ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:56:32.063248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.295294ms","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":"info","ts":"2026-07-06T01:56:32.0633Z","caller":"traceutil/trace.go:171","msg":"trace[1736362869] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:3737; }","duration":"135.403866ms","start":"2026-07-06T01:56:31.927889Z","end":"2026-07-06T01:56:32.063293Z","steps":["trace[1736362869] 'agreement among raft nodes before linearized reading' (duration: 135.061217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:56:39.663891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.90582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:56:39.665019Z","caller":"traceutil/trace.go:171","msg":"trace[687393131] transaction","detail":"{read_only:false; response_revision:3779; number_of_response:1; }","duration":"194.939496ms","start":"2026-07-06T01:56:39.470062Z","end":"2026-07-06T01:56:39.665001Z","steps":["trace[687393131] 'process raft request' (duration: 55.823056ms)","trace[687393131] 'compare' (duration: 137.752295ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:56:46.296693Z","caller":"traceutil/trace.go:171","msg":"trace[2091457579] transaction","detail":"{read_only:false; response_revision:3819; number_of_response:1; }","duration":"100.125298ms","start":"2026-07-06T01:56:46.196544Z","end":"2026-07-06T01:56:46.296669Z","steps":["trace[2091457579] 'process raft request' (duration: 99.984564ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:46.325117Z","caller":"traceutil/trace.go:171","msg":"trace[1604776060] transaction","detail":"{read_only:false; response_revision:3822; number_of_response:1; }","duration":"125.152774ms","start":"2026-07-06T01:56:46.199939Z","end":"2026-07-06T01:56:46.325092Z","steps":["trace[1604776060] 'process raft request' (duration: 125.109393ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:46.325319Z","caller":"traceutil/trace.go:171","msg":"trace[410568120] transaction","detail":"{read_only:false; response_revision:3820; number_of_response:1; }","duration":"128.12642ms","start":"2026-07-06T01:56:46.197157Z","end":"2026-07-06T01:56:46.325284Z","steps":["trace[410568120] 'process raft request' (duration: 127.662929ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:56:46.325358Z","caller":"traceutil/trace.go:171","msg":"trace[2018359067] transaction","detail":"{read_only:false; response_revision:3821; number_of_response:1; }","duration":"127.537935ms","start":"2026-07-06T01:56:46.197784Z","end":"2026-07-06T01:56:46.325322Z","steps":["trace[2018359067] 'process raft request' (duration: 127.223527ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:14.168399Z","caller":"traceutil/trace.go:171","msg":"trace[693296580] transaction","detail":"{read_only:false; response_revision:4405; number_of_response:1; }","duration":"102.348387ms","start":"2026-07-06T01:57:14.066024Z","end":"2026-07-06T01:57:14.168372Z","steps":["trace[693296580] 'process raft request' (duration: 102.177793ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:14.798855Z","caller":"traceutil/trace.go:171","msg":"trace[1188798890] transaction","detail":"{read_only:false; response_revision:4414; number_of_response:1; }","duration":"106.499863ms","start":"2026-07-06T01:57:14.692326Z","end":"2026-07-06T01:57:14.798826Z","steps":["trace[1188798890] 'process raft request' (duration: 106.36119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:16.087473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.688483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:57:16.088016Z","caller":"traceutil/trace.go:171","msg":"trace[183468874] transaction","detail":"{read_only:false; response_revision:4443; number_of_response:1; }","duration":"311.457717ms","start":"2026-07-06T01:57:15.776529Z","end":"2026-07-06T01:57:16.087986Z","steps":["trace[183468874] 'process raft request' (duration: 103.187689ms)","trace[183468874] 'compare' (duration: 207.290362ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:57:16.088138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:15.776501Z","time spent":"311.58009ms","remote":"127.0.0.1:40270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:57:16.088207Z","caller":"traceutil/trace.go:171","msg":"trace[171867338] transaction","detail":"{read_only:false; response_revision:4444; number_of_response:1; }","duration":"174.746998ms","start":"2026-07-06T01:57:15.91344Z","end":"2026-07-06T01:57:16.088187Z","steps":["trace[171867338] 'process raft request' (duration: 174.41645ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:16.088317Z","caller":"traceutil/trace.go:171","msg":"trace[207531139] transaction","detail":"{read_only:false; response_revision:4445; number_of_response:1; }","duration":"171.853833ms","start":"2026-07-06T01:57:15.916443Z","end":"2026-07-06T01:57:16.088297Z","steps":["trace[207531139] 'process raft request' (duration: 171.478773ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:16.088255Z","caller":"traceutil/trace.go:171","msg":"trace[1349286982] linearizableReadLoop","detail":"{readStateIndex:4611; appliedIndex:4608; }","duration":"161.772104ms","start":"2026-07-06T01:57:15.926468Z","end":"2026-07-06T01:57:16.08824Z","steps":["trace[1349286982] 'read index received' (duration: 149.231472ms)","trace[1349286982] 'applied index is now lower than readState.Index' (duration: 12.539412ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:57:16.088723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.251287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40b965cc-cd2b-4b7e-8dea-42f219c2854b\" ","response":"range_response_count:1 size:4079"} {"level":"info","ts":"2026-07-06T01:57:16.088762Z","caller":"traceutil/trace.go:171","msg":"trace[2092846129] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40b965cc-cd2b-4b7e-8dea-42f219c2854b; range_end:; response_count:1; response_revision:4445; }","duration":"162.317039ms","start":"2026-07-06T01:57:15.926433Z","end":"2026-07-06T01:57:16.08875Z","steps":["trace[2092846129] 'agreement among raft nodes before linearized reading' (duration: 162.181025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:19.091672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.742513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:57:19.091738Z","caller":"traceutil/trace.go:171","msg":"trace[592030202] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4521; }","duration":"213.820655ms","start":"2026-07-06T01:57:18.877904Z","end":"2026-07-06T01:57:19.091724Z","steps":["trace[592030202] 'count revisions from in-memory index tree' (duration: 213.609709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:19.092121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.730605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:57:19.092305Z","caller":"traceutil/trace.go:171","msg":"trace[1871827042] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"188.850193ms","start":"2026-07-06T01:57:18.903442Z","end":"2026-07-06T01:57:19.092292Z","steps":["trace[1871827042] 'process raft request' (duration: 49.852232ms)","trace[1871827042] 'compare' (duration: 138.621062ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:57:19.363039Z","caller":"traceutil/trace.go:171","msg":"trace[584892598] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"104.739052ms","start":"2026-07-06T01:57:19.258247Z","end":"2026-07-06T01:57:19.362986Z","steps":["trace[584892598] 'process raft request' (duration: 104.392973ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:19.495429Z","caller":"traceutil/trace.go:171","msg":"trace[1405457238] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"124.557071ms","start":"2026-07-06T01:57:19.370041Z","end":"2026-07-06T01:57:19.494598Z","steps":["trace[1405457238] 'process raft request' (duration: 124.425168ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:19.537882Z","caller":"traceutil/trace.go:171","msg":"trace[1220760656] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"167.67953ms","start":"2026-07-06T01:57:19.370175Z","end":"2026-07-06T01:57:19.537855Z","steps":["trace[1220760656] 'process raft request' (duration: 167.617478ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:19.53857Z","caller":"traceutil/trace.go:171","msg":"trace[1337507836] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"168.475561ms","start":"2026-07-06T01:57:19.370071Z","end":"2026-07-06T01:57:19.538547Z","steps":["trace[1337507836] 'process raft request' (duration: 167.485655ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:25.122043Z","caller":"traceutil/trace.go:171","msg":"trace[482063266] transaction","detail":"{read_only:false; response_revision:4575; number_of_response:1; }","duration":"527.769455ms","start":"2026-07-06T01:57:24.594253Z","end":"2026-07-06T01:57:25.122023Z","steps":["trace[482063266] 'process raft request' (duration: 527.638441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:25.122176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:24.594227Z","time spent":"527.882888ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2724,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:57:25.51474Z","caller":"traceutil/trace.go:171","msg":"trace[1195176185] linearizableReadLoop","detail":"{readStateIndex:4748; appliedIndex:4747; }","duration":"346.157662ms","start":"2026-07-06T01:57:25.168557Z","end":"2026-07-06T01:57:25.514715Z","steps":["trace[1195176185] 'read index received' (duration: 346.028229ms)","trace[1195176185] 'applied index is now lower than readState.Index' (duration: 128.233µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:57:25.515118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.535261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-d6c878f7c-lw52x.18bf90ab81595c34\" ","response":"range_response_count:1 size:893"} {"level":"info","ts":"2026-07-06T01:57:25.515232Z","caller":"traceutil/trace.go:171","msg":"trace[1088843664] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-d6c878f7c-lw52x.18bf90ab81595c34; range_end:; response_count:1; response_revision:4577; }","duration":"346.696355ms","start":"2026-07-06T01:57:25.168513Z","end":"2026-07-06T01:57:25.515209Z","steps":["trace[1088843664] 'agreement among raft nodes before linearized reading' (duration: 346.349337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:25.515331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:25.168494Z","time spent":"346.822739ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":916,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-d6c878f7c-lw52x.18bf90ab81595c34\" "} {"level":"info","ts":"2026-07-06T01:57:25.514827Z","caller":"traceutil/trace.go:171","msg":"trace[651364110] transaction","detail":"{read_only:false; response_revision:4577; number_of_response:1; }","duration":"350.00198ms","start":"2026-07-06T01:57:25.164762Z","end":"2026-07-06T01:57:25.514764Z","steps":["trace[651364110] 'process raft request' (duration: 349.819586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:25.517463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:25.164749Z","time spent":"351.875918ms","remote":"127.0.0.1:40278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1250,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T01:57:25.541303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.808178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:57:25.541449Z","caller":"traceutil/trace.go:171","msg":"trace[285713899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4578; }","duration":"212.995463ms","start":"2026-07-06T01:57:25.328431Z","end":"2026-07-06T01:57:25.541427Z","steps":["trace[285713899] 'agreement among raft nodes before linearized reading' (duration: 212.799228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:25.541459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.565714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-06T01:57:25.541806Z","caller":"traceutil/trace.go:171","msg":"trace[2039947615] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4578; }","duration":"280.926902ms","start":"2026-07-06T01:57:25.260854Z","end":"2026-07-06T01:57:25.541781Z","steps":["trace[2039947615] 'agreement among raft nodes before linearized reading' (duration: 280.43112ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:25.541339Z","caller":"traceutil/trace.go:171","msg":"trace[1630598885] transaction","detail":"{read_only:false; response_revision:4578; number_of_response:1; }","duration":"368.372503ms","start":"2026-07-06T01:57:25.172947Z","end":"2026-07-06T01:57:25.54132Z","steps":["trace[1630598885] 'process raft request' (duration: 368.115296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:25.541969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:25.172917Z","time spent":"368.971449ms","remote":"127.0.0.1:40270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13278,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:57:25.970607Z","caller":"traceutil/trace.go:171","msg":"trace[1092451801] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"286.581978ms","start":"2026-07-06T01:57:25.683993Z","end":"2026-07-06T01:57:25.970575Z","steps":["trace[1092451801] 'process raft request' (duration: 286.416614ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:25.970635Z","caller":"traceutil/trace.go:171","msg":"trace[1755271906] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"286.397713ms","start":"2026-07-06T01:57:25.684217Z","end":"2026-07-06T01:57:25.970615Z","steps":["trace[1755271906] 'process raft request' (duration: 286.350532ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:25.970787Z","caller":"traceutil/trace.go:171","msg":"trace[1666932777] transaction","detail":"{read_only:false; response_revision:4593; number_of_response:1; }","duration":"286.594108ms","start":"2026-07-06T01:57:25.684056Z","end":"2026-07-06T01:57:25.97065Z","steps":["trace[1666932777] 'process raft request' (duration: 286.453854ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:26.241232Z","caller":"traceutil/trace.go:171","msg":"trace[1770215595] linearizableReadLoop","detail":"{readStateIndex:4771; appliedIndex:4769; }","duration":"145.208589ms","start":"2026-07-06T01:57:26.096007Z","end":"2026-07-06T01:57:26.241216Z","steps":["trace[1770215595] 'read index received' (duration: 15.502479ms)","trace[1770215595] 'applied index is now lower than readState.Index' (duration: 129.70524ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:57:26.2414Z","caller":"traceutil/trace.go:171","msg":"trace[1818732847] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"145.583679ms","start":"2026-07-06T01:57:26.095804Z","end":"2026-07-06T01:57:26.241388Z","steps":["trace[1818732847] 'process raft request' (duration: 145.319672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:26.242036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.01167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-06T01:57:26.242078Z","caller":"traceutil/trace.go:171","msg":"trace[1384942469] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4600; }","duration":"146.086532ms","start":"2026-07-06T01:57:26.095983Z","end":"2026-07-06T01:57:26.242069Z","steps":["trace[1384942469] 'agreement among raft nodes before linearized reading' (duration: 145.573768ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:26.243483Z","caller":"traceutil/trace.go:171","msg":"trace[2061576824] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"126.037966ms","start":"2026-07-06T01:57:26.117433Z","end":"2026-07-06T01:57:26.243471Z","steps":["trace[2061576824] 'process raft request' (duration: 124.563228ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:26.243077Z","caller":"traceutil/trace.go:171","msg":"trace[492454042] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"128.277373ms","start":"2026-07-06T01:57:26.114759Z","end":"2026-07-06T01:57:26.243036Z","steps":["trace[492454042] 'process raft request' (duration: 127.168344ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:28.617388Z","caller":"traceutil/trace.go:171","msg":"trace[1294783801] linearizableReadLoop","detail":"{readStateIndex:4831; appliedIndex:4830; }","duration":"336.165242ms","start":"2026-07-06T01:57:28.281111Z","end":"2026-07-06T01:57:28.617276Z","steps":["trace[1294783801] 'read index received' (duration: 335.962837ms)","trace[1294783801] 'applied index is now lower than readState.Index' (duration: 201.805µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:57:28.617399Z","caller":"traceutil/trace.go:171","msg":"trace[1445641565] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"368.549066ms","start":"2026-07-06T01:57:28.248822Z","end":"2026-07-06T01:57:28.617371Z","steps":["trace[1445641565] 'process raft request' (duration: 368.293479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:28.617548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:28.248788Z","time spent":"368.675419ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:57:28.617583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.238532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:57:28.617607Z","caller":"traceutil/trace.go:171","msg":"trace[1885426899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4660; }","duration":"289.303723ms","start":"2026-07-06T01:57:28.328296Z","end":"2026-07-06T01:57:28.6176Z","steps":["trace[1885426899] 'agreement among raft nodes before linearized reading' (duration: 289.254232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:28.617643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.297655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-06T01:57:28.617693Z","caller":"traceutil/trace.go:171","msg":"trace[1826712636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4660; }","duration":"128.374967ms","start":"2026-07-06T01:57:28.489306Z","end":"2026-07-06T01:57:28.617681Z","steps":["trace[1826712636] 'agreement among raft nodes before linearized reading' (duration: 128.254534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:28.617755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.74135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d028bcf-481d-4021-bcff-5efef86746a2\" ","response":"range_response_count:1 size:3939"} {"level":"info","ts":"2026-07-06T01:57:28.617833Z","caller":"traceutil/trace.go:171","msg":"trace[1879216276] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d028bcf-481d-4021-bcff-5efef86746a2; range_end:; response_count:1; response_revision:4660; }","duration":"243.843373ms","start":"2026-07-06T01:57:28.373966Z","end":"2026-07-06T01:57:28.61781Z","steps":["trace[1879216276] 'agreement among raft nodes before linearized reading' (duration: 243.697849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:28.617848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.442853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-06T01:57:28.617916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.332936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:57:28.617928Z","caller":"traceutil/trace.go:171","msg":"trace[319392287] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4660; }","duration":"238.570696ms","start":"2026-07-06T01:57:28.379344Z","end":"2026-07-06T01:57:28.617915Z","steps":["trace[319392287] 'agreement among raft nodes before linearized reading' (duration: 238.293549ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:28.617957Z","caller":"traceutil/trace.go:171","msg":"trace[7929753] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:4660; }","duration":"187.393498ms","start":"2026-07-06T01:57:28.43055Z","end":"2026-07-06T01:57:28.617943Z","steps":["trace[7929753] 'agreement among raft nodes before linearized reading' (duration: 187.328066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:28.617989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.992672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T01:57:28.618012Z","caller":"traceutil/trace.go:171","msg":"trace[102334794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4660; }","duration":"257.071003ms","start":"2026-07-06T01:57:28.360935Z","end":"2026-07-06T01:57:28.618006Z","steps":["trace[102334794] 'agreement among raft nodes before linearized reading' (duration: 256.854008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:28.618275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.501211ms","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-07-06T01:57:28.618329Z","caller":"traceutil/trace.go:171","msg":"trace[1147995719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4660; }","duration":"337.25085ms","start":"2026-07-06T01:57:28.281066Z","end":"2026-07-06T01:57:28.618317Z","steps":["trace[1147995719] 'agreement among raft nodes before linearized reading' (duration: 336.373318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:28.618364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:28.281045Z","time spent":"337.307281ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T01:57:39.032067Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.294714968s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-06T01:57:39.164317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.88576338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-07-06T01:57:39.164389Z","caller":"traceutil/trace.go:171","msg":"trace[43247770] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4776; }","duration":"1.885874223s","start":"2026-07-06T01:57:37.278497Z","end":"2026-07-06T01:57:39.164371Z","steps":["trace[43247770] 'range keys from in-memory index tree' (duration: 1.885593576s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.164426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:37.278459Z","time spent":"1.885958725s","remote":"127.0.0.1:40270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-06T01:57:39.164558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836261543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:57:39.164613Z","caller":"traceutil/trace.go:171","msg":"trace[919835053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4776; }","duration":"1.836339494s","start":"2026-07-06T01:57:37.328259Z","end":"2026-07-06T01:57:39.164598Z","steps":["trace[919835053] 'range keys from in-memory index tree' (duration: 1.836209411s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.164649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:37.328248Z","time spent":"1.836394426s","remote":"127.0.0.1:40110","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-07-06T01:57:39.164747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.877940308s","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-07-06T01:57:39.164809Z","caller":"traceutil/trace.go:171","msg":"trace[864633458] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4776; }","duration":"1.878043051s","start":"2026-07-06T01:57:37.286756Z","end":"2026-07-06T01:57:39.164799Z","steps":["trace[864633458] 'range keys from in-memory index tree' (duration: 1.877704782s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.164851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:37.286734Z","time spent":"1.878108402s","remote":"127.0.0.1:40244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-06T01:57:39.165185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.788711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:57:39.165266Z","caller":"traceutil/trace.go:171","msg":"trace[1546364789] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"1.428057312s","start":"2026-07-06T01:57:37.737197Z","end":"2026-07-06T01:57:39.165254Z","steps":["trace[1546364789] 'process raft request' (duration: 1.295153288s)","trace[1546364789] 'compare' (duration: 132.410091ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:57:39.16534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:37.737168Z","time spent":"1.428149254s","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:57:39.186044Z","caller":"traceutil/trace.go:171","msg":"trace[1531592146] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"1.324339135s","start":"2026-07-06T01:57:37.861682Z","end":"2026-07-06T01:57:39.186021Z","steps":["trace[1531592146] 'process raft request' (duration: 1.32417827s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.18618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:37.861657Z","time spent":"1.324452487s","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:57:39.186285Z","caller":"traceutil/trace.go:171","msg":"trace[224284583] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4952; }","duration":"422.35826ms","start":"2026-07-06T01:57:38.763905Z","end":"2026-07-06T01:57:39.186263Z","steps":["trace[224284583] 'read index received' (duration: 268.468624ms)","trace[224284583] 'applied index is now lower than readState.Index' (duration: 153.888736ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:57:39.186353Z","caller":"traceutil/trace.go:171","msg":"trace[2009192243] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"578.198265ms","start":"2026-07-06T01:57:38.608143Z","end":"2026-07-06T01:57:39.186341Z","steps":["trace[2009192243] 'process raft request' (duration: 577.839675ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:39.186381Z","caller":"traceutil/trace.go:171","msg":"trace[594862616] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"484.986857ms","start":"2026-07-06T01:57:38.701383Z","end":"2026-07-06T01:57:39.186369Z","steps":["trace[594862616] 'process raft request' (duration: 484.758812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.186409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:38.608093Z","time spent":"578.286247ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:57:39.186502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:38.701357Z","time spent":"485.057339ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:57:39.186743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.826082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-06T01:57:39.186913Z","caller":"traceutil/trace.go:171","msg":"trace[589601503] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4780; }","duration":"422.949785ms","start":"2026-07-06T01:57:38.763853Z","end":"2026-07-06T01:57:39.186803Z","steps":["trace[589601503] 'agreement among raft nodes before linearized reading' (duration: 422.513863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.18697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:38.763833Z","time spent":"423.12342ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-06T01:57:39.426108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.188043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:57:39.426329Z","caller":"traceutil/trace.go:171","msg":"trace[1338556839] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"224.307015ms","start":"2026-07-06T01:57:39.202005Z","end":"2026-07-06T01:57:39.426312Z","steps":["trace[1338556839] 'process raft request' (duration: 224.268574ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:39.426339Z","caller":"traceutil/trace.go:171","msg":"trace[2047967946] linearizableReadLoop","detail":"{readStateIndex:4957; appliedIndex:4956; }","duration":"239.974509ms","start":"2026-07-06T01:57:39.186341Z","end":"2026-07-06T01:57:39.426316Z","steps":["trace[2047967946] 'read index received' (duration: 109.500238ms)","trace[2047967946] 'applied index is now lower than readState.Index' (duration: 130.471621ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:57:39.426518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.992843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-06T01:57:39.426572Z","caller":"traceutil/trace.go:171","msg":"trace[780774014] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4783; }","duration":"647.085215ms","start":"2026-07-06T01:57:38.779476Z","end":"2026-07-06T01:57:39.426561Z","steps":["trace[780774014] 'agreement among raft nodes before linearized reading' (duration: 646.906241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.426607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:38.779458Z","time spent":"647.140036ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-06T01:57:39.426632Z","caller":"traceutil/trace.go:171","msg":"trace[834918328] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"234.924938ms","start":"2026-07-06T01:57:39.191694Z","end":"2026-07-06T01:57:39.426618Z","steps":["trace[834918328] 'process raft request' (duration: 234.520958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.42672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.144347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:57:39.426786Z","caller":"traceutil/trace.go:171","msg":"trace[25334191] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4783; }","duration":"371.348043ms","start":"2026-07-06T01:57:39.05542Z","end":"2026-07-06T01:57:39.426768Z","steps":["trace[25334191] 'agreement among raft nodes before linearized reading' (duration: 371.109467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.426852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.029924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T01:57:39.426901Z","caller":"traceutil/trace.go:171","msg":"trace[1857077495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4783; }","duration":"647.114216ms","start":"2026-07-06T01:57:38.779775Z","end":"2026-07-06T01:57:39.426889Z","steps":["trace[1857077495] 'agreement among raft nodes before linearized reading' (duration: 647.007912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.426932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:39.055346Z","time spent":"371.574549ms","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-07-06T01:57:39.427019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.856041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-06T01:57:39.427051Z","caller":"traceutil/trace.go:171","msg":"trace[1413036479] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4783; }","duration":"569.923663ms","start":"2026-07-06T01:57:38.85712Z","end":"2026-07-06T01:57:39.427044Z","steps":["trace[1413036479] 'agreement among raft nodes before linearized reading' (duration: 569.837831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.427079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:38.857099Z","time spent":"569.973763ms","remote":"127.0.0.1:40270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-06T01:57:39.427176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.211887ms","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-07-06T01:57:39.427227Z","caller":"traceutil/trace.go:171","msg":"trace[530196208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4783; }","duration":"640.30088ms","start":"2026-07-06T01:57:38.786917Z","end":"2026-07-06T01:57:39.427217Z","steps":["trace[530196208] 'agreement among raft nodes before linearized reading' (duration: 640.184027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.427258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:38.786912Z","time spent":"640.339171ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T01:57:39.427281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.618542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-06T01:57:39.42731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.523126ms","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-07-06T01:57:39.427333Z","caller":"traceutil/trace.go:171","msg":"trace[2006316092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4783; }","duration":"639.697844ms","start":"2026-07-06T01:57:38.787619Z","end":"2026-07-06T01:57:39.427317Z","steps":["trace[2006316092] 'agreement among raft nodes before linearized reading' (duration: 639.50016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.427414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:38.787615Z","time spent":"639.787996ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T01:57:39.427423Z","caller":"traceutil/trace.go:171","msg":"trace[326788871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4783; }","duration":"640.580417ms","start":"2026-07-06T01:57:38.786765Z","end":"2026-07-06T01:57:39.427346Z","steps":["trace[326788871] 'agreement among raft nodes before linearized reading' (duration: 640.477805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.427458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:38.786759Z","time spent":"640.69107ms","remote":"127.0.0.1:40344","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-07-06T01:57:39.427533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.730201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:57:39.42757Z","caller":"traceutil/trace.go:171","msg":"trace[641170219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4783; }","duration":"261.814373ms","start":"2026-07-06T01:57:39.165744Z","end":"2026-07-06T01:57:39.427559Z","steps":["trace[641170219] 'agreement among raft nodes before linearized reading' (duration: 261.761891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.426938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:38.779769Z","time spent":"647.160858ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-06T01:57:39.428001Z","caller":"traceutil/trace.go:171","msg":"trace[524777737] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"251.025615ms","start":"2026-07-06T01:57:39.176958Z","end":"2026-07-06T01:57:39.427984Z","steps":["trace[524777737] 'process raft request' (duration: 118.885191ms)","trace[524777737] 'compare' (duration: 130.05925ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:57:39.43259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.989819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8d028bcf-481d-4021-bcff-5efef86746a2\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-06T01:57:39.432675Z","caller":"traceutil/trace.go:171","msg":"trace[1334869129] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8d028bcf-481d-4021-bcff-5efef86746a2; range_end:; response_count:1; response_revision:4783; }","duration":"264.103193ms","start":"2026-07-06T01:57:39.16855Z","end":"2026-07-06T01:57:39.432653Z","steps":["trace[1334869129] 'agreement among raft nodes before linearized reading' (duration: 258.687902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.962434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.728474ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T01:57:39.962674Z","caller":"traceutil/trace.go:171","msg":"trace[1029972925] linearizableReadLoop","detail":"{readStateIndex:4973; appliedIndex:4972; }","duration":"396.077702ms","start":"2026-07-06T01:57:39.566578Z","end":"2026-07-06T01:57:39.962656Z","steps":["trace[1029972925] 'read index received' (duration: 10.110022ms)","trace[1029972925] 'applied index is now lower than readState.Index' (duration: 385.96665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:57:39.962976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.38758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:57:39.963026Z","caller":"traceutil/trace.go:171","msg":"trace[422296758] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4796; }","duration":"396.481982ms","start":"2026-07-06T01:57:39.566535Z","end":"2026-07-06T01:57:39.963017Z","steps":["trace[422296758] 'agreement among raft nodes before linearized reading' (duration: 396.40488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.963058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:39.566517Z","time spent":"396.533673ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-06T01:57:39.963727Z","caller":"traceutil/trace.go:171","msg":"trace[1878999902] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"394.586333ms","start":"2026-07-06T01:57:39.569114Z","end":"2026-07-06T01:57:39.963701Z","steps":["trace[1878999902] 'process raft request' (duration: 393.418463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:57:39.964161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:39.569098Z","time spent":"394.698967ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10925,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:57:40.044105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.115444ms","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-07-06T01:57:40.044185Z","caller":"traceutil/trace.go:171","msg":"trace[793380443] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4797; }","duration":"458.243438ms","start":"2026-07-06T01:57:39.585921Z","end":"2026-07-06T01:57:40.044165Z","steps":["trace[793380443] 'agreement among raft nodes before linearized reading' (duration: 377.779949ms)","trace[793380443] 'count revisions from in-memory index tree' (duration: 80.335275ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:57:40.044245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:57:39.585905Z","time spent":"458.303829ms","remote":"127.0.0.1:36230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-06T01:57:51.435356Z","caller":"traceutil/trace.go:171","msg":"trace[2056539416] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5062; }","duration":"105.288197ms","start":"2026-07-06T01:57:51.330042Z","end":"2026-07-06T01:57:51.43533Z","steps":["trace[2056539416] 'read index received' (duration: 100.648857ms)","trace[2056539416] 'applied index is now lower than readState.Index' (duration: 4.63758ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:57:51.435603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.40803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:57:51.435653Z","caller":"traceutil/trace.go:171","msg":"trace[652456206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4884; }","duration":"105.623486ms","start":"2026-07-06T01:57:51.330014Z","end":"2026-07-06T01:57:51.435637Z","steps":["trace[652456206] 'agreement among raft nodes before linearized reading' (duration: 105.40499ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:57:51.43561Z","caller":"traceutil/trace.go:171","msg":"trace[124790450] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"212.189736ms","start":"2026-07-06T01:57:51.223291Z","end":"2026-07-06T01:57:51.435481Z","steps":["trace[124790450] 'process raft request' (duration: 207.345431ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:01.421472Z","caller":"traceutil/trace.go:171","msg":"trace[546476441] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"178.226785ms","start":"2026-07-06T01:58:01.24321Z","end":"2026-07-06T01:58:01.421436Z","steps":["trace[546476441] 'process raft request' (duration: 177.901387ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:09.709402Z","caller":"traceutil/trace.go:171","msg":"trace[698913282] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"214.916776ms","start":"2026-07-06T01:58:09.494469Z","end":"2026-07-06T01:58:09.709385Z","steps":["trace[698913282] 'process raft request' (duration: 171.169379ms)","trace[698913282] 'compare' (duration: 43.624724ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:09.776277Z","caller":"traceutil/trace.go:171","msg":"trace[1254691790] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"225.767888ms","start":"2026-07-06T01:58:09.550479Z","end":"2026-07-06T01:58:09.776247Z","steps":["trace[1254691790] 'process raft request' (duration: 225.344627ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:09.801731Z","caller":"traceutil/trace.go:171","msg":"trace[1005866617] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5170; }","duration":"172.878863ms","start":"2026-07-06T01:58:09.628828Z","end":"2026-07-06T01:58:09.801707Z","steps":["trace[1005866617] 'read index received' (duration: 36.803717ms)","trace[1005866617] 'applied index is now lower than readState.Index' (duration: 136.074066ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:58:09.80191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.054798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/keystone-openid-metadata\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:58:09.801963Z","caller":"traceutil/trace.go:171","msg":"trace[1641752062] range","detail":"{range_begin:/registry/configmaps/openstack/keystone-openid-metadata; range_end:; response_count:0; response_revision:4990; }","duration":"173.161611ms","start":"2026-07-06T01:58:09.62879Z","end":"2026-07-06T01:58:09.801952Z","steps":["trace[1641752062] 'agreement among raft nodes before linearized reading' (duration: 173.055119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:09.802124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.352416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-06T01:58:09.802545Z","caller":"traceutil/trace.go:171","msg":"trace[41569056] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4990; }","duration":"148.831559ms","start":"2026-07-06T01:58:09.653691Z","end":"2026-07-06T01:58:09.802523Z","steps":["trace[41569056] 'agreement among raft nodes before linearized reading' (duration: 148.206843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:09.802354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.083784ms","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-07-06T01:58:09.802644Z","caller":"traceutil/trace.go:171","msg":"trace[1287944012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4990; }","duration":"102.410933ms","start":"2026-07-06T01:58:09.700219Z","end":"2026-07-06T01:58:09.80263Z","steps":["trace[1287944012] 'agreement among raft nodes before linearized reading' (duration: 102.041063ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:11.529316Z","caller":"traceutil/trace.go:171","msg":"trace[1170189306] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5181; }","duration":"100.914114ms","start":"2026-07-06T01:58:11.428382Z","end":"2026-07-06T01:58:11.529296Z","steps":["trace[1170189306] 'read index received' (duration: 100.647457ms)","trace[1170189306] 'applied index is now lower than readState.Index' (duration: 265.786µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:11.529418Z","caller":"traceutil/trace.go:171","msg":"trace[574227471] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"102.894276ms","start":"2026-07-06T01:58:11.426497Z","end":"2026-07-06T01:58:11.529391Z","steps":["trace[574227471] 'process raft request' (duration: 102.632919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:11.529507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.101859ms","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-07-06T01:58:11.529547Z","caller":"traceutil/trace.go:171","msg":"trace[1035151292] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4999; }","duration":"101.16914ms","start":"2026-07-06T01:58:11.428367Z","end":"2026-07-06T01:58:11.529536Z","steps":["trace[1035151292] 'agreement among raft nodes before linearized reading' (duration: 101.014137ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:11.630814Z","caller":"traceutil/trace.go:171","msg":"trace[1793701241] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"107.555837ms","start":"2026-07-06T01:58:11.523235Z","end":"2026-07-06T01:58:11.630791Z","steps":["trace[1793701241] 'process raft request' (duration: 42.961737ms)","trace[1793701241] 'compare' (duration: 64.372274ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:11.943706Z","caller":"traceutil/trace.go:171","msg":"trace[2035056056] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"229.004574ms","start":"2026-07-06T01:58:11.71467Z","end":"2026-07-06T01:58:11.943675Z","steps":["trace[2035056056] 'process raft request' (duration: 140.939214ms)","trace[2035056056] 'compare' (duration: 87.906027ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:12.013291Z","caller":"traceutil/trace.go:171","msg":"trace[1707620616] linearizableReadLoop","detail":"{readStateIndex:5185; appliedIndex:5183; }","duration":"198.917722ms","start":"2026-07-06T01:58:11.814337Z","end":"2026-07-06T01:58:12.013255Z","steps":["trace[1707620616] 'read index received' (duration: 41.268643ms)","trace[1707620616] 'applied index is now lower than readState.Index' (duration: 157.648259ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:12.013404Z","caller":"traceutil/trace.go:171","msg":"trace[110588410] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"231.967532ms","start":"2026-07-06T01:58:11.781425Z","end":"2026-07-06T01:58:12.013393Z","steps":["trace[110588410] 'process raft request' (duration: 231.703665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:12.013622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.272142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T01:58:12.013692Z","caller":"traceutil/trace.go:171","msg":"trace[1661667537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5002; }","duration":"199.387915ms","start":"2026-07-06T01:58:11.814291Z","end":"2026-07-06T01:58:12.013679Z","steps":["trace[1661667537] 'agreement among raft nodes before linearized reading' (duration: 199.215521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:12.013702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.412013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-06T01:58:12.013706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.331722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-06T01:58:12.013819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.979708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-06T01:58:12.013839Z","caller":"traceutil/trace.go:171","msg":"trace[679356260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5002; }","duration":"158.03613ms","start":"2026-07-06T01:58:11.855798Z","end":"2026-07-06T01:58:12.013834Z","steps":["trace[679356260] 'agreement among raft nodes before linearized reading' (duration: 157.958078ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:12.013843Z","caller":"traceutil/trace.go:171","msg":"trace[1580840000] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5002; }","duration":"157.501855ms","start":"2026-07-06T01:58:11.856314Z","end":"2026-07-06T01:58:12.013816Z","steps":["trace[1580840000] 'agreement among raft nodes before linearized reading' (duration: 157.228369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:12.013623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.770743ms","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-07-06T01:58:12.014102Z","caller":"traceutil/trace.go:171","msg":"trace[1622216431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5002; }","duration":"158.224374ms","start":"2026-07-06T01:58:11.855786Z","end":"2026-07-06T01:58:12.014011Z","steps":["trace[1622216431] 'agreement among raft nodes before linearized reading' (duration: 157.739412ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:12.013742Z","caller":"traceutil/trace.go:171","msg":"trace[533197190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5002; }","duration":"157.469315ms","start":"2026-07-06T01:58:11.856263Z","end":"2026-07-06T01:58:12.013732Z","steps":["trace[533197190] 'agreement among raft nodes before linearized reading' (duration: 157.386063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:12.013964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.402637ms","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-07-06T01:58:12.014413Z","caller":"traceutil/trace.go:171","msg":"trace[1980765540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5002; }","duration":"156.869639ms","start":"2026-07-06T01:58:11.857534Z","end":"2026-07-06T01:58:12.014404Z","steps":["trace[1980765540] 'agreement among raft nodes before linearized reading' (duration: 156.385376ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:13.013995Z","caller":"traceutil/trace.go:171","msg":"trace[803275675] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"134.868548ms","start":"2026-07-06T01:58:12.879102Z","end":"2026-07-06T01:58:13.013971Z","steps":["trace[803275675] 'process raft request' (duration: 134.61473ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:15.580126Z","caller":"traceutil/trace.go:171","msg":"trace[1270542216] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"129.050318ms","start":"2026-07-06T01:58:15.45104Z","end":"2026-07-06T01:58:15.58009Z","steps":["trace[1270542216] 'process raft request' (duration: 128.842483ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:27.648141Z","caller":"traceutil/trace.go:171","msg":"trace[1999191827] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"129.803595ms","start":"2026-07-06T01:58:27.518303Z","end":"2026-07-06T01:58:27.648106Z","steps":["trace[1999191827] 'process raft request' (duration: 129.604439ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:27.835601Z","caller":"traceutil/trace.go:171","msg":"trace[1124877663] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"100.48599ms","start":"2026-07-06T01:58:27.735092Z","end":"2026-07-06T01:58:27.835578Z","steps":["trace[1124877663] 'process raft request' (duration: 100.314615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:28.286331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.021733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:58:28.286542Z","caller":"traceutil/trace.go:171","msg":"trace[1059596919] linearizableReadLoop","detail":"{readStateIndex:5369; appliedIndex:5367; }","duration":"117.211496ms","start":"2026-07-06T01:58:28.169313Z","end":"2026-07-06T01:58:28.286525Z","steps":["trace[1059596919] 'read index received' (duration: 32.260831ms)","trace[1059596919] 'applied index is now lower than readState.Index' (duration: 84.949735ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:28.28665Z","caller":"traceutil/trace.go:171","msg":"trace[665361832] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"215.485429ms","start":"2026-07-06T01:58:28.071151Z","end":"2026-07-06T01:58:28.286637Z","steps":["trace[665361832] 'process raft request' (duration: 68.073705ms)","trace[665361832] 'compare' (duration: 146.903251ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:58:28.28697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.100652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-06T01:58:28.287018Z","caller":"traceutil/trace.go:171","msg":"trace[751222809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5181; }","duration":"102.188234ms","start":"2026-07-06T01:58:28.18482Z","end":"2026-07-06T01:58:28.287008Z","steps":["trace[751222809] 'agreement among raft nodes before linearized reading' (duration: 102.049941ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:28.287032Z","caller":"traceutil/trace.go:171","msg":"trace[170608945] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"214.081511ms","start":"2026-07-06T01:58:28.072928Z","end":"2026-07-06T01:58:28.28701Z","steps":["trace[170608945] 'process raft request' (duration: 213.512137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:28.286942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.604577ms","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-07-06T01:58:28.287292Z","caller":"traceutil/trace.go:171","msg":"trace[3253509] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5181; }","duration":"117.945956ms","start":"2026-07-06T01:58:28.169271Z","end":"2026-07-06T01:58:28.287217Z","steps":["trace[3253509] 'agreement among raft nodes before linearized reading' (duration: 117.454182ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:28.45239Z","caller":"traceutil/trace.go:171","msg":"trace[568927986] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"158.00724ms","start":"2026-07-06T01:58:28.294354Z","end":"2026-07-06T01:58:28.452361Z","steps":["trace[568927986] 'process raft request' (duration: 115.368698ms)","trace[568927986] 'compare' (duration: 42.502127ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:28.645538Z","caller":"traceutil/trace.go:171","msg":"trace[2079373451] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"351.074614ms","start":"2026-07-06T01:58:28.294437Z","end":"2026-07-06T01:58:28.645512Z","steps":["trace[2079373451] 'process raft request' (duration: 351.003412ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:28.645605Z","caller":"traceutil/trace.go:171","msg":"trace[1422089631] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"351.165967ms","start":"2026-07-06T01:58:28.294406Z","end":"2026-07-06T01:58:28.645572Z","steps":["trace[1422089631] 'process raft request' (duration: 288.37505ms)","trace[1422089631] 'compare' (duration: 62.518629ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:58:28.645703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:28.294412Z","time spent":"351.207248ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:58:28.64605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:28.294386Z","time spent":"351.290361ms","remote":"127.0.0.1:40344","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-07-06T01:58:28.646106Z","caller":"traceutil/trace.go:171","msg":"trace[78869239] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"351.638939ms","start":"2026-07-06T01:58:28.294462Z","end":"2026-07-06T01:58:28.646101Z","steps":["trace[78869239] 'process raft request' (duration: 351.034053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:28.646239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:28.294449Z","time spent":"351.68269ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:58:28.646275Z","caller":"traceutil/trace.go:171","msg":"trace[1308743515] linearizableReadLoop","detail":"{readStateIndex:5376; appliedIndex:5369; }","duration":"315.689442ms","start":"2026-07-06T01:58:28.330568Z","end":"2026-07-06T01:58:28.646257Z","steps":["trace[1308743515] 'read index received' (duration: 79.166494ms)","trace[1308743515] 'applied index is now lower than readState.Index' (duration: 236.522158ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:28.64634Z","caller":"traceutil/trace.go:171","msg":"trace[1958409586] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"351.756213ms","start":"2026-07-06T01:58:28.294577Z","end":"2026-07-06T01:58:28.646333Z","steps":["trace[1958409586] 'process raft request' (duration: 351.431044ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:28.646058Z","caller":"traceutil/trace.go:171","msg":"trace[157671585] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"351.568297ms","start":"2026-07-06T01:58:28.294465Z","end":"2026-07-06T01:58:28.646033Z","steps":["trace[157671585] 'process raft request' (duration: 351.503406ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:28.646378Z","caller":"traceutil/trace.go:171","msg":"trace[568384983] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"350.906451ms","start":"2026-07-06T01:58:28.295461Z","end":"2026-07-06T01:58:28.646367Z","steps":["trace[568384983] 'process raft request' (duration: 350.697565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:28.646383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:28.294564Z","time spent":"351.795394ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:58:28.646424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:28.294447Z","time spent":"351.939737ms","remote":"127.0.0.1:40344","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-07-06T01:58:28.646448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:28.29545Z","time spent":"350.953852ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T01:58:28.646598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.02728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:58:28.646634Z","caller":"traceutil/trace.go:171","msg":"trace[2133034242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5188; }","duration":"316.087102ms","start":"2026-07-06T01:58:28.330534Z","end":"2026-07-06T01:58:28.646621Z","steps":["trace[2133034242] 'agreement among raft nodes before linearized reading' (duration: 316.032941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:28.646666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:28.330517Z","time spent":"316.140053ms","remote":"127.0.0.1:40110","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-07-06T01:58:28.973624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.969928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T01:58:28.973609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.917096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:58:28.973678Z","caller":"traceutil/trace.go:171","msg":"trace[266788548] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5190; }","duration":"199.074851ms","start":"2026-07-06T01:58:28.774592Z","end":"2026-07-06T01:58:28.973667Z","steps":["trace[266788548] 'range keys from in-memory index tree' (duration: 198.862025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:28.973676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.845325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-07-06T01:58:28.973707Z","caller":"traceutil/trace.go:171","msg":"trace[1458828804] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5190; }","duration":"199.06554ms","start":"2026-07-06T01:58:28.774616Z","end":"2026-07-06T01:58:28.973682Z","steps":["trace[1458828804] 'range keys from in-memory index tree' (duration: 198.805044ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:28.973731Z","caller":"traceutil/trace.go:171","msg":"trace[1085658277] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5190; }","duration":"188.907086ms","start":"2026-07-06T01:58:28.784809Z","end":"2026-07-06T01:58:28.973717Z","steps":["trace[1085658277] 'range keys from in-memory index tree' (duration: 188.620878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:29.78696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.660294ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T01:58:29.903045Z","caller":"traceutil/trace.go:171","msg":"trace[2120527881] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"160.350372ms","start":"2026-07-06T01:58:29.742682Z","end":"2026-07-06T01:58:29.903033Z","steps":["trace[2120527881] 'process raft request' (duration: 160.255899ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:30.080585Z","caller":"traceutil/trace.go:171","msg":"trace[959486939] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"239.98256ms","start":"2026-07-06T01:58:29.840583Z","end":"2026-07-06T01:58:30.080566Z","steps":["trace[959486939] 'process raft request' (duration: 210.806689ms)","trace[959486939] 'compare' (duration: 28.688899ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:32.744121Z","caller":"traceutil/trace.go:171","msg":"trace[665816660] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"182.600074ms","start":"2026-07-06T01:58:32.561501Z","end":"2026-07-06T01:58:32.744101Z","steps":["trace[665816660] 'process raft request' (duration: 182.07925ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:34.003624Z","caller":"traceutil/trace.go:171","msg":"trace[1143978606] linearizableReadLoop","detail":"{readStateIndex:5402; appliedIndex:5401; }","duration":"217.878876ms","start":"2026-07-06T01:58:33.785723Z","end":"2026-07-06T01:58:34.003602Z","steps":["trace[1143978606] 'read index received' (duration: 217.66255ms)","trace[1143978606] 'applied index is now lower than readState.Index' (duration: 215.456µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:34.003686Z","caller":"traceutil/trace.go:171","msg":"trace[2100419167] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"450.493597ms","start":"2026-07-06T01:58:33.553165Z","end":"2026-07-06T01:58:34.003658Z","steps":["trace[2100419167] 'process raft request' (duration: 450.274452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:34.00396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:33.55315Z","time spent":"450.736083ms","remote":"127.0.0.1:46666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:58:34.004043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.286845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:58:34.004162Z","caller":"traceutil/trace.go:171","msg":"trace[989007427] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5213; }","duration":"218.459751ms","start":"2026-07-06T01:58:33.785682Z","end":"2026-07-06T01:58:34.004142Z","steps":["trace[989007427] 'agreement among raft nodes before linearized reading' (duration: 218.024479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:34.744061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.565317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T01:58:34.744509Z","caller":"traceutil/trace.go:171","msg":"trace[1774380403] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"647.677874ms","start":"2026-07-06T01:58:34.096792Z","end":"2026-07-06T01:58:34.74447Z","steps":["trace[1774380403] 'process raft request' (duration: 216.360287ms)","trace[1774380403] 'compare' (duration: 430.167817ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:58:34.744647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:34.096768Z","time spent":"647.822848ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:58:34.745102Z","caller":"traceutil/trace.go:171","msg":"trace[2100429294] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"646.068452ms","start":"2026-07-06T01:58:34.09898Z","end":"2026-07-06T01:58:34.745048Z","steps":["trace[2100429294] 'process raft request' (duration: 645.20887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:34.745522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:34.098967Z","time spent":"646.262958ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T01:58:34.754815Z","caller":"traceutil/trace.go:171","msg":"trace[1458328900] linearizableReadLoop","detail":"{readStateIndex:5405; appliedIndex:5402; }","duration":"428.7128ms","start":"2026-07-06T01:58:34.326084Z","end":"2026-07-06T01:58:34.754797Z","steps":["trace[1458328900] 'read index received' (duration: 82.165555ms)","trace[1458328900] 'applied index is now lower than readState.Index' (duration: 346.546385ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:34.754892Z","caller":"traceutil/trace.go:171","msg":"trace[1953926243] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"349.909973ms","start":"2026-07-06T01:58:34.404952Z","end":"2026-07-06T01:58:34.754862Z","steps":["trace[1953926243] 'process raft request' (duration: 349.79738ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:34.754879Z","caller":"traceutil/trace.go:171","msg":"trace[931834255] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"437.14806ms","start":"2026-07-06T01:58:34.317702Z","end":"2026-07-06T01:58:34.75485Z","steps":["trace[931834255] 'process raft request' (duration: 436.923665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:34.755029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:34.404926Z","time spent":"350.019826ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:58:34.755092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:34.317684Z","time spent":"437.341415ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T01:58:34.755241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.60451ms","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-07-06T01:58:34.755306Z","caller":"traceutil/trace.go:171","msg":"trace[408545953] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:5217; }","duration":"227.710704ms","start":"2026-07-06T01:58:34.527579Z","end":"2026-07-06T01:58:34.755289Z","steps":["trace[408545953] 'agreement among raft nodes before linearized reading' (duration: 227.544889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:34.755567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.48249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T01:58:34.755615Z","caller":"traceutil/trace.go:171","msg":"trace[1672521319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5217; }","duration":"429.530641ms","start":"2026-07-06T01:58:34.326071Z","end":"2026-07-06T01:58:34.755602Z","steps":["trace[1672521319] 'agreement among raft nodes before linearized reading' (duration: 428.885874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:34.755675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T01:58:34.326037Z","time spent":"429.627383ms","remote":"127.0.0.1:40122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T01:58:41.028188Z","caller":"traceutil/trace.go:171","msg":"trace[64181324] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"188.32275ms","start":"2026-07-06T01:58:40.839825Z","end":"2026-07-06T01:58:41.028148Z","steps":["trace[64181324] 'process raft request' (duration: 150.305366ms)","trace[64181324] 'compare' (duration: 37.757736ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:41.028328Z","caller":"traceutil/trace.go:171","msg":"trace[2127990674] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"185.65452ms","start":"2026-07-06T01:58:40.842643Z","end":"2026-07-06T01:58:41.028298Z","steps":["trace[2127990674] 'process raft request' (duration: 185.490556ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:58:41.0284Z","caller":"traceutil/trace.go:171","msg":"trace[169594162] linearizableReadLoop","detail":"{readStateIndex:5461; appliedIndex:5459; }","duration":"185.393983ms","start":"2026-07-06T01:58:40.842983Z","end":"2026-07-06T01:58:41.028377Z","steps":["trace[169594162] 'read index received' (duration: 147.178454ms)","trace[169594162] 'applied index is now lower than readState.Index' (duration: 38.214449ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T01:58:41.028453Z","caller":"traceutil/trace.go:171","msg":"trace[520576311] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"116.441892ms","start":"2026-07-06T01:58:40.911992Z","end":"2026-07-06T01:58:41.028434Z","steps":["trace[520576311] 'process raft request' (duration: 116.235197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T01:58:41.028611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.613858ms","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-07-06T01:58:41.028656Z","caller":"traceutil/trace.go:171","msg":"trace[372435708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5271; }","duration":"185.706181ms","start":"2026-07-06T01:58:40.842937Z","end":"2026-07-06T01:58:41.028643Z","steps":["trace[372435708] 'agreement among raft nodes before linearized reading' (duration: 185.558397ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:59:05.029145Z","caller":"traceutil/trace.go:171","msg":"trace[1675743428] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"108.156664ms","start":"2026-07-06T01:59:04.920955Z","end":"2026-07-06T01:59:05.029112Z","steps":["trace[1675743428] 'process raft request' (duration: 107.913688ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:59:07.014694Z","caller":"traceutil/trace.go:171","msg":"trace[584840029] transaction","detail":"{read_only:false; response_revision:5458; number_of_response:1; }","duration":"126.084385ms","start":"2026-07-06T01:59:06.888581Z","end":"2026-07-06T01:59:07.014665Z","steps":["trace[584840029] 'process raft request' (duration: 124.549615ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:59:11.482194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2836} {"level":"warn","ts":"2026-07-06T01:59:11.711487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.430522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-06T01:59:11.71157Z","caller":"traceutil/trace.go:171","msg":"trace[296591776] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5493; }","duration":"193.588827ms","start":"2026-07-06T01:59:11.517962Z","end":"2026-07-06T01:59:11.711551Z","steps":["trace[296591776] 'range keys from in-memory index tree' (duration: 193.221737ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T01:59:11.855921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2836,"took":"372.340895ms","hash":2772513746,"current-db-size-bytes":53346304,"current-db-size":"53 MB","current-db-size-in-use-bytes":28942336,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-06T01:59:11.85599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2772513746,"revision":2836,"compact-revision":-1} {"level":"info","ts":"2026-07-06T01:59:14.731444Z","caller":"traceutil/trace.go:171","msg":"trace[689226945] linearizableReadLoop","detail":"{readStateIndex:5703; appliedIndex:5702; }","duration":"169.99259ms","start":"2026-07-06T01:59:14.561424Z","end":"2026-07-06T01:59:14.731417Z","steps":["trace[689226945] 'read index received' (duration: 169.888346ms)","trace[689226945] 'applied index is now lower than readState.Index' (duration: 102.404µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T01:59:14.731917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.299097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T01:59:14.732041Z","caller":"traceutil/trace.go:171","msg":"trace[1568709316] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:5504; }","duration":"170.617316ms","start":"2026-07-06T01:59:14.561397Z","end":"2026-07-06T01:59:14.732014Z","steps":["trace[1568709316] 'agreement among raft nodes before linearized reading' (duration: 170.124224ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T02:00:37.511763Z","caller":"traceutil/trace.go:171","msg":"trace[761882636] transaction","detail":"{read_only:false; response_revision:5985; number_of_response:1; }","duration":"139.72471ms","start":"2026-07-06T02:00:37.372013Z","end":"2026-07-06T02:00:37.511738Z","steps":["trace[761882636] 'process raft request' (duration: 139.558145ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T02:00:37.542058Z","caller":"traceutil/trace.go:171","msg":"trace[1702004479] transaction","detail":"{read_only:false; response_revision:5986; number_of_response:1; }","duration":"166.241919ms","start":"2026-07-06T02:00:37.375792Z","end":"2026-07-06T02:00:37.542034Z","steps":["trace[1702004479] 'process raft request' (duration: 166.090215ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T02:00:47.743789Z","caller":"traceutil/trace.go:171","msg":"trace[1956209659] transaction","detail":"{read_only:false; response_revision:6040; number_of_response:1; }","duration":"174.153121ms","start":"2026-07-06T02:00:47.569614Z","end":"2026-07-06T02:00:47.743767Z","steps":["trace[1956209659] 'process raft request' (duration: 174.10829ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T02:00:47.743865Z","caller":"traceutil/trace.go:171","msg":"trace[1160313820] transaction","detail":"{read_only:false; response_revision:6039; number_of_response:1; }","duration":"178.829635ms","start":"2026-07-06T02:00:47.565Z","end":"2026-07-06T02:00:47.74383Z","steps":["trace[1160313820] 'process raft request' (duration: 172.901309ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T02:01:27.654949Z","caller":"traceutil/trace.go:171","msg":"trace[1373678045] transaction","detail":"{read_only:false; response_revision:6430; number_of_response:1; }","duration":"132.726023ms","start":"2026-07-06T02:01:27.522206Z","end":"2026-07-06T02:01:27.654932Z","steps":["trace[1373678045] 'process raft request' (duration: 132.614489ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T02:01:52.661219Z","caller":"traceutil/trace.go:171","msg":"trace[1306007061] transaction","detail":"{read_only:false; response_revision:6610; number_of_response:1; }","duration":"933.994002ms","start":"2026-07-06T02:01:51.727201Z","end":"2026-07-06T02:01:52.661195Z","steps":["trace[1306007061] 'process raft request' (duration: 933.889039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T02:01:52.661376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T02:01:51.727184Z","time spent":"934.125615ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T02:01:56.797322Z","caller":"traceutil/trace.go:171","msg":"trace[260479822] transaction","detail":"{read_only:false; response_revision:6633; number_of_response:1; }","duration":"110.506698ms","start":"2026-07-06T02:01:56.686792Z","end":"2026-07-06T02:01:56.797299Z","steps":["trace[260479822] 'process raft request' (duration: 110.066486ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T02:01:56.798549Z","caller":"traceutil/trace.go:171","msg":"trace[1731881097] transaction","detail":"{read_only:false; response_revision:6634; number_of_response:1; }","duration":"110.007074ms","start":"2026-07-06T02:01:56.688528Z","end":"2026-07-06T02:01:56.798535Z","steps":["trace[1731881097] 'process raft request' (duration: 108.449052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T02:03:40.122454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.537525ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T02:04:11.487587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5493} {"level":"info","ts":"2026-07-06T02:04:11.566278Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5493,"took":"77.454789ms","hash":1945118102,"current-db-size-bytes":53346304,"current-db-size":"53 MB","current-db-size-in-use-bytes":18186240,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-06T02:04:11.56635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1945118102,"revision":5493,"compact-revision":2836}