{"level":"warn","ts":"2026-08-05T11:06:25.575757Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:06:25.575842Z","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-08-05T11:06:25.575943Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:06:25.575955Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-05T11:06:25.575976Z","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-08-05T11:06:25.576642Z","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-08-05T11:06:25.576722Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-05T11:06:25.581131Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.965589ms"} {"level":"info","ts":"2026-08-05T11:06:25.586576Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-08-05T11:06:25.586709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T11:06:25.586751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-08-05T11:06:25.586774Z","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-08-05T11:06:25.586806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-08-05T11:06:25.586881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-08-05T11:06:25.594953Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T11:06:25.597843Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T11:06:25.599891Z","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-08-05T11:06:25.60355Z","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-08-05T11:06:25.603771Z","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-08-05T11:06:25.60386Z","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-08-05T11:06:25.604168Z","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-08-05T11:06:25.60422Z","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-08-05T11:06:25.60496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-08-05T11:06:25.605175Z","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-08-05T11:06:25.608884Z","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-08-05T11:06:25.609037Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-05T11:06:25.609224Z","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-08-05T11:06:25.609129Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-05T11:06:25.609253Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T11:06:25.988004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T11:06:25.988075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T11:06:25.988131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-08-05T11:06:25.988153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-08-05T11:06:25.988165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-05T11:06:25.988186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-08-05T11:06:25.988206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-05T11:06:25.98929Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:06:25.99064Z","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-08-05T11:06:25.990783Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:06:25.990836Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:06:25.990897Z","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-08-05T11:06:25.991151Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:06:25.991334Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:06:25.992152Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T11:06:25.992287Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T11:06:25.993387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T11:06:25.995288Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"warn","ts":"2026-08-05T11:06:44.271852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.694554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-08-05T11:06:44.271986Z","caller":"traceutil/trace.go:171","msg":"trace[1510177965] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:373; }","duration":"103.91528ms","start":"2026-08-05T11:06:44.168049Z","end":"2026-08-05T11:06:44.271964Z","steps":["trace[1510177965] 'range keys from in-memory index tree' (duration: 103.52835ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:06:44.453367Z","caller":"traceutil/trace.go:171","msg":"trace[981070510] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"173.813004ms","start":"2026-08-05T11:06:44.27951Z","end":"2026-08-05T11:06:44.453323Z","steps":["trace[981070510] 'process raft request' (duration: 173.532626ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.629331Z","caller":"traceutil/trace.go:171","msg":"trace[1826017324] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"190.9195ms","start":"2026-08-05T11:07:01.438383Z","end":"2026-08-05T11:07:01.629303Z","steps":["trace[1826017324] 'process raft request' (duration: 190.830307ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.629349Z","caller":"traceutil/trace.go:171","msg":"trace[1317295067] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"191.047914ms","start":"2026-08-05T11:07:01.438263Z","end":"2026-08-05T11:07:01.629311Z","steps":["trace[1317295067] 'process raft request' (duration: 190.042316ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.629662Z","caller":"traceutil/trace.go:171","msg":"trace[1635259951] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"186.572459ms","start":"2026-08-05T11:07:01.443056Z","end":"2026-08-05T11:07:01.629629Z","steps":["trace[1635259951] 'process raft request' (duration: 186.438205ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.629672Z","caller":"traceutil/trace.go:171","msg":"trace[437971163] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"187.393392ms","start":"2026-08-05T11:07:01.442264Z","end":"2026-08-05T11:07:01.629657Z","steps":["trace[437971163] 'process raft request' (duration: 186.997631ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.635466Z","caller":"traceutil/trace.go:171","msg":"trace[625470952] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"188.177513ms","start":"2026-08-05T11:07:01.447264Z","end":"2026-08-05T11:07:01.635442Z","steps":["trace[625470952] 'process raft request' (duration: 187.960857ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.635494Z","caller":"traceutil/trace.go:171","msg":"trace[632028242] transaction","detail":"{read_only:false; number_of_response:1; response_revision:495; }","duration":"182.871806ms","start":"2026-08-05T11:07:01.452595Z","end":"2026-08-05T11:07:01.635467Z","steps":["trace[632028242] 'process raft request' (duration: 182.740602ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.635659Z","caller":"traceutil/trace.go:171","msg":"trace[515538047] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"179.356459ms","start":"2026-08-05T11:07:01.456278Z","end":"2026-08-05T11:07:01.635634Z","steps":["trace[515538047] 'process raft request' (duration: 179.206075ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.635739Z","caller":"traceutil/trace.go:171","msg":"trace[399281958] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"178.982868ms","start":"2026-08-05T11:07:01.456741Z","end":"2026-08-05T11:07:01.635724Z","steps":["trace[399281958] 'process raft request' (duration: 178.807723ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.635597Z","caller":"traceutil/trace.go:171","msg":"trace[1779353111] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"181.305352ms","start":"2026-08-05T11:07:01.454273Z","end":"2026-08-05T11:07:01.635578Z","steps":["trace[1779353111] 'process raft request' (duration: 181.128397ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.635871Z","caller":"traceutil/trace.go:171","msg":"trace[2116923431] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"174.007369ms","start":"2026-08-05T11:07:01.461827Z","end":"2026-08-05T11:07:01.635834Z","steps":["trace[2116923431] 'process raft request' (duration: 173.830214ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.635997Z","caller":"traceutil/trace.go:171","msg":"trace[169129365] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"172.984722ms","start":"2026-08-05T11:07:01.463002Z","end":"2026-08-05T11:07:01.635986Z","steps":["trace[169129365] 'process raft request' (duration: 172.728554ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.636151Z","caller":"traceutil/trace.go:171","msg":"trace[1178184575] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"153.87553ms","start":"2026-08-05T11:07:01.482242Z","end":"2026-08-05T11:07:01.636118Z","steps":["trace[1178184575] 'process raft request' (duration: 153.717726ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:01.636311Z","caller":"traceutil/trace.go:171","msg":"trace[308972637] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:510; }","duration":"146.907726ms","start":"2026-08-05T11:07:01.489378Z","end":"2026-08-05T11:07:01.636285Z","steps":["trace[308972637] 'read index received' (duration: 139.014416ms)","trace[308972637] 'applied index is now lower than readState.Index' (duration: 7.89233ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:07:01.636473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.07933ms","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-08-05T11:07:01.636538Z","caller":"traceutil/trace.go:171","msg":"trace[1926670108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:502; }","duration":"147.159112ms","start":"2026-08-05T11:07:01.489361Z","end":"2026-08-05T11:07:01.63652Z","steps":["trace[1926670108] 'agreement among raft nodes before linearized reading' (duration: 147.015528ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:07.796197Z","caller":"traceutil/trace.go:171","msg":"trace[381014190] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"107.730867ms","start":"2026-08-05T11:07:07.688435Z","end":"2026-08-05T11:07:07.796166Z","steps":["trace[381014190] 'process raft request' (duration: 107.460389ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:07:08.491011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.695591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:07:08.491369Z","caller":"traceutil/trace.go:171","msg":"trace[596279879] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"444.783061ms","start":"2026-08-05T11:07:08.046549Z","end":"2026-08-05T11:07:08.491332Z","steps":["trace[596279879] 'process raft request' (duration: 156.139253ms)","trace[596279879] 'compare' (duration: 287.558198ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:07:08.491465Z","caller":"traceutil/trace.go:171","msg":"trace[1856225955] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"434.788302ms","start":"2026-08-05T11:07:08.056661Z","end":"2026-08-05T11:07:08.49145Z","steps":["trace[1856225955] 'process raft request' (duration: 434.479114ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:08.491401Z","caller":"traceutil/trace.go:171","msg":"trace[349547111] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"391.850409ms","start":"2026-08-05T11:07:08.099533Z","end":"2026-08-05T11:07:08.491383Z","steps":["trace[349547111] 'process raft request' (duration: 391.775206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:07:08.491622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:07:08.056635Z","time spent":"434.937727ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:07:08.491618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:07:08.046531Z","time spent":"444.955265ms","remote":"127.0.0.1:37684","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-08-05T11:07:08.491488Z","caller":"traceutil/trace.go:171","msg":"trace[1127745547] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:609; }","duration":"290.259633ms","start":"2026-08-05T11:07:08.201173Z","end":"2026-08-05T11:07:08.491432Z","steps":["trace[1127745547] 'read index received' (duration: 1.531823ms)","trace[1127745547] 'applied index is now lower than readState.Index' (duration: 288.72108ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:07:08.491817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.623633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-05T11:07:08.491842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:07:08.099502Z","time spent":"392.192198ms","remote":"127.0.0.1:37656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T11:07:08.491929Z","caller":"traceutil/trace.go:171","msg":"trace[1313328824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"290.792968ms","start":"2026-08-05T11:07:08.201106Z","end":"2026-08-05T11:07:08.491899Z","steps":["trace[1313328824] 'agreement among raft nodes before linearized reading' (duration: 290.462949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:07:08.491854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.114711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:07:08.492076Z","caller":"traceutil/trace.go:171","msg":"trace[546083324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"124.363699ms","start":"2026-08-05T11:07:08.367682Z","end":"2026-08-05T11:07:08.492045Z","steps":["trace[546083324] 'agreement among raft nodes before linearized reading' (duration: 124.125412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:32.571361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.220396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:08:32.571455Z","caller":"traceutil/trace.go:171","msg":"trace[302850774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1785; }","duration":"203.36734ms","start":"2026-08-05T11:08:32.36807Z","end":"2026-08-05T11:08:32.571437Z","steps":["trace[302850774] 'range keys from in-memory index tree' (duration: 203.108173ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:32.650013Z","caller":"traceutil/trace.go:171","msg":"trace[1938715431] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"148.523934ms","start":"2026-08-05T11:08:32.50146Z","end":"2026-08-05T11:08:32.649984Z","steps":["trace[1938715431] 'read index received' (duration: 148.216705ms)","trace[1938715431] 'applied index is now lower than readState.Index' (duration: 306.059µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:08:32.650069Z","caller":"traceutil/trace.go:171","msg":"trace[1460968281] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"175.111073ms","start":"2026-08-05T11:08:32.474926Z","end":"2026-08-05T11:08:32.650037Z","steps":["trace[1460968281] 'process raft request' (duration: 174.833636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:32.650364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.39249ms","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":"warn","ts":"2026-08-05T11:08:32.65036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.874893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54c6a533-c0e4-485c-a44f-a5fdb9902427\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-05T11:08:32.650455Z","caller":"traceutil/trace.go:171","msg":"trace[1467005857] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54c6a533-c0e4-485c-a44f-a5fdb9902427; range_end:; response_count:1; response_revision:1786; }","duration":"149.025937ms","start":"2026-08-05T11:08:32.501406Z","end":"2026-08-05T11:08:32.650432Z","steps":["trace[1467005857] 'agreement among raft nodes before linearized reading' (duration: 148.717899ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:32.650441Z","caller":"traceutil/trace.go:171","msg":"trace[947064339] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1786; }","duration":"125.456842ms","start":"2026-08-05T11:08:32.524944Z","end":"2026-08-05T11:08:32.650401Z","steps":["trace[947064339] 'agreement among raft nodes before linearized reading' (duration: 125.190614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:32.862823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.777096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:08:32.862973Z","caller":"traceutil/trace.go:171","msg":"trace[92161596] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"206.926608ms","start":"2026-08-05T11:08:32.656026Z","end":"2026-08-05T11:08:32.862953Z","steps":["trace[92161596] 'process raft request' (duration: 92.925566ms)","trace[92161596] 'compare' (duration: 113.632592ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:08:32.894248Z","caller":"traceutil/trace.go:171","msg":"trace[1455190597] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"238.064054ms","start":"2026-08-05T11:08:32.656167Z","end":"2026-08-05T11:08:32.894231Z","steps":["trace[1455190597] 'process raft request' (duration: 237.90844ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:33.159752Z","caller":"traceutil/trace.go:171","msg":"trace[1946494137] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"216.936857ms","start":"2026-08-05T11:08:32.942764Z","end":"2026-08-05T11:08:33.159701Z","steps":["trace[1946494137] 'process raft request' (duration: 143.203505ms)","trace[1946494137] 'compare' (duration: 73.220807ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:08:33.15988Z","caller":"traceutil/trace.go:171","msg":"trace[718621011] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"213.296936ms","start":"2026-08-05T11:08:32.946569Z","end":"2026-08-05T11:08:33.159866Z","steps":["trace[718621011] 'process raft request' (duration: 212.994538ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:33.159752Z","caller":"traceutil/trace.go:171","msg":"trace[14376496] linearizableReadLoop","detail":"{readStateIndex:1859; appliedIndex:1858; }","duration":"216.767352ms","start":"2026-08-05T11:08:32.942905Z","end":"2026-08-05T11:08:33.159673Z","steps":["trace[14376496] 'read index received' (duration: 143.073462ms)","trace[14376496] 'applied index is now lower than readState.Index' (duration: 73.69286ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:08:33.160068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.136663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-05T11:08:33.160408Z","caller":"traceutil/trace.go:171","msg":"trace[678458558] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1790; }","duration":"217.501902ms","start":"2026-08-05T11:08:32.942875Z","end":"2026-08-05T11:08:33.160377Z","steps":["trace[678458558] 'agreement among raft nodes before linearized reading' (duration: 216.922646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:33.160819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.145549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-05T11:08:33.161105Z","caller":"traceutil/trace.go:171","msg":"trace[461008301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1790; }","duration":"145.215791ms","start":"2026-08-05T11:08:33.015639Z","end":"2026-08-05T11:08:33.160855Z","steps":["trace[461008301] 'agreement among raft nodes before linearized reading' (duration: 144.789749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:09:16.512366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.322663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:09:16.512456Z","caller":"traceutil/trace.go:171","msg":"trace[511767284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2082; }","duration":"144.451926ms","start":"2026-08-05T11:09:16.367986Z","end":"2026-08-05T11:09:16.512438Z","steps":["trace[511767284] 'range keys from in-memory index tree' (duration: 144.248421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:09:16.512441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.96657ms","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-08-05T11:09:16.512532Z","caller":"traceutil/trace.go:171","msg":"trace[1462112295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2082; }","duration":"118.118814ms","start":"2026-08-05T11:09:16.394393Z","end":"2026-08-05T11:09:16.512512Z","steps":["trace[1462112295] 'range keys from in-memory index tree' (duration: 117.760875ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:09:16.517359Z","caller":"traceutil/trace.go:171","msg":"trace[1310404508] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"121.92696ms","start":"2026-08-05T11:09:16.395404Z","end":"2026-08-05T11:09:16.517331Z","steps":["trace[1310404508] 'process raft request' (duration: 121.663803ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:09:22.721201Z","caller":"traceutil/trace.go:171","msg":"trace[1332556000] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2193; }","duration":"176.502057ms","start":"2026-08-05T11:09:22.544673Z","end":"2026-08-05T11:09:22.721175Z","steps":["trace[1332556000] 'read index received' (duration: 176.316902ms)","trace[1332556000] 'applied index is now lower than readState.Index' (duration: 184.205µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:09:22.721328Z","caller":"traceutil/trace.go:171","msg":"trace[1783704] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"187.078242ms","start":"2026-08-05T11:09:22.534207Z","end":"2026-08-05T11:09:22.721285Z","steps":["trace[1783704] 'process raft request' (duration: 186.807494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:09:22.721448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.763565ms","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-08-05T11:09:22.721502Z","caller":"traceutil/trace.go:171","msg":"trace[1875507443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2114; }","duration":"176.869128ms","start":"2026-08-05T11:09:22.54462Z","end":"2026-08-05T11:09:22.721489Z","steps":["trace[1875507443] 'agreement among raft nodes before linearized reading' (duration: 176.696653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:09:22.721652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.964567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-05T11:09:22.721747Z","caller":"traceutil/trace.go:171","msg":"trace[573246725] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2114; }","duration":"165.09665ms","start":"2026-08-05T11:09:22.556631Z","end":"2026-08-05T11:09:22.721728Z","steps":["trace[573246725] 'agreement among raft nodes before linearized reading' (duration: 164.900745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:10:12.415427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.847725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:10:12.415518Z","caller":"traceutil/trace.go:171","msg":"trace[95001940] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2558; }","duration":"105.989669ms","start":"2026-08-05T11:10:12.309511Z","end":"2026-08-05T11:10:12.415501Z","steps":["trace[95001940] 'count revisions from in-memory index tree' (duration: 105.722932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:10:41.198604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.85367ms","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-08-05T11:10:41.198756Z","caller":"traceutil/trace.go:171","msg":"trace[2007006008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2733; }","duration":"122.045735ms","start":"2026-08-05T11:10:41.076684Z","end":"2026-08-05T11:10:41.19873Z","steps":["trace[2007006008] 'range keys from in-memory index tree' (duration: 121.537851ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:10:47.281472Z","caller":"traceutil/trace.go:171","msg":"trace[328835141] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"115.585079ms","start":"2026-08-05T11:10:47.165862Z","end":"2026-08-05T11:10:47.281447Z","steps":["trace[328835141] 'process raft request' (duration: 114.042176ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:13:12.756543Z","caller":"traceutil/trace.go:171","msg":"trace[2139416965] transaction","detail":"{read_only:false; response_revision:3733; number_of_response:1; }","duration":"122.49755ms","start":"2026-08-05T11:13:12.634016Z","end":"2026-08-05T11:13:12.756513Z","steps":["trace[2139416965] 'process raft request' (duration: 122.295204ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:13:12.759165Z","caller":"traceutil/trace.go:171","msg":"trace[1457403231] linearizableReadLoop","detail":"{readStateIndex:3874; appliedIndex:3871; }","duration":"123.93281ms","start":"2026-08-05T11:13:12.635199Z","end":"2026-08-05T11:13:12.759132Z","steps":["trace[1457403231] 'read index received' (duration: 121.127211ms)","trace[1457403231] 'applied index is now lower than readState.Index' (duration: 2.804149ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:13:12.759374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.133915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:13:12.759425Z","caller":"traceutil/trace.go:171","msg":"trace[1100481057] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:3736; }","duration":"124.232828ms","start":"2026-08-05T11:13:12.635174Z","end":"2026-08-05T11:13:12.759407Z","steps":["trace[1100481057] 'agreement among raft nodes before linearized reading' (duration: 124.094374ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:13:12.759793Z","caller":"traceutil/trace.go:171","msg":"trace[147881428] transaction","detail":"{read_only:false; response_revision:3735; number_of_response:1; }","duration":"124.753682ms","start":"2026-08-05T11:13:12.635016Z","end":"2026-08-05T11:13:12.75977Z","steps":["trace[147881428] 'process raft request' (duration: 124.013182ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:13:12.759723Z","caller":"traceutil/trace.go:171","msg":"trace[716771307] transaction","detail":"{read_only:false; response_revision:3734; number_of_response:1; }","duration":"125.266457ms","start":"2026-08-05T11:13:12.634093Z","end":"2026-08-05T11:13:12.75936Z","steps":["trace[716771307] 'process raft request' (duration: 124.798024ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:14:31.531453Z","caller":"traceutil/trace.go:171","msg":"trace[1324324939] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4672; }","duration":"106.277721ms","start":"2026-08-05T11:14:31.425152Z","end":"2026-08-05T11:14:31.531429Z","steps":["trace[1324324939] 'process raft request' (duration: 89.065123ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:14:39.99416Z","caller":"traceutil/trace.go:171","msg":"trace[1406203804] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"114.48386ms","start":"2026-08-05T11:14:39.879642Z","end":"2026-08-05T11:14:39.994126Z","steps":["trace[1406203804] 'process raft request' (duration: 114.254213ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:14:39.995454Z","caller":"traceutil/trace.go:171","msg":"trace[1957564528] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"115.272032ms","start":"2026-08-05T11:14:39.880165Z","end":"2026-08-05T11:14:39.995437Z","steps":["trace[1957564528] 'process raft request' (duration: 114.659785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:14:50.989127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.627241ms","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-08-05T11:14:50.989221Z","caller":"traceutil/trace.go:171","msg":"trace[72548250] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5151; }","duration":"236.774825ms","start":"2026-08-05T11:14:50.752427Z","end":"2026-08-05T11:14:50.989202Z","steps":["trace[72548250] 'range keys from in-memory index tree' (duration: 236.426536ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:14:56.234298Z","caller":"traceutil/trace.go:171","msg":"trace[1103187236] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"104.613276ms","start":"2026-08-05T11:14:56.129652Z","end":"2026-08-05T11:14:56.234265Z","steps":["trace[1103187236] 'process raft request' (duration: 82.496802ms)","trace[1103187236] 'compare' (duration: 21.787784ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:15:11.298601Z","caller":"traceutil/trace.go:171","msg":"trace[2125692423] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"192.251708ms","start":"2026-08-05T11:15:11.106331Z","end":"2026-08-05T11:15:11.298583Z","steps":["trace[2125692423] 'process raft request' (duration: 192.150865ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:15:11.408143Z","caller":"traceutil/trace.go:171","msg":"trace[1974828358] linearizableReadLoop","detail":"{readStateIndex:5683; appliedIndex:5681; }","duration":"163.91592ms","start":"2026-08-05T11:15:11.244205Z","end":"2026-08-05T11:15:11.408121Z","steps":["trace[1974828358] 'read index received' (duration: 54.358623ms)","trace[1974828358] 'applied index is now lower than readState.Index' (duration: 109.556117ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:15:11.408282Z","caller":"traceutil/trace.go:171","msg":"trace[1960282955] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"208.733627ms","start":"2026-08-05T11:15:11.199512Z","end":"2026-08-05T11:15:11.408246Z","steps":["trace[1960282955] 'process raft request' (duration: 208.48494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:15:11.408328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.109025ms","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-08-05T11:15:11.408537Z","caller":"traceutil/trace.go:171","msg":"trace[317879776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5485; }","duration":"164.322171ms","start":"2026-08-05T11:15:11.244168Z","end":"2026-08-05T11:15:11.40849Z","steps":["trace[317879776] 'agreement among raft nodes before linearized reading' (duration: 164.064764ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:15:11.81005Z","caller":"traceutil/trace.go:171","msg":"trace[1757782551] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"107.722977ms","start":"2026-08-05T11:15:11.702297Z","end":"2026-08-05T11:15:11.81002Z","steps":["trace[1757782551] 'process raft request' (duration: 66.078738ms)","trace[1757782551] 'compare' (duration: 41.416082ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:15:11.810359Z","caller":"traceutil/trace.go:171","msg":"trace[1955472132] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"108.042225ms","start":"2026-08-05T11:15:11.702301Z","end":"2026-08-05T11:15:11.810343Z","steps":["trace[1955472132] 'process raft request' (duration: 107.674315ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:15:11.810467Z","caller":"traceutil/trace.go:171","msg":"trace[764352519] transaction","detail":"{read_only:false; response_revision:5493; number_of_response:1; }","duration":"108.123948ms","start":"2026-08-05T11:15:11.702332Z","end":"2026-08-05T11:15:11.810456Z","steps":["trace[764352519] 'process raft request' (duration: 107.915502ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:15:11.810442Z","caller":"traceutil/trace.go:171","msg":"trace[1661681807] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"108.083177ms","start":"2026-08-05T11:15:11.702333Z","end":"2026-08-05T11:15:11.810416Z","steps":["trace[1661681807] 'process raft request' (duration: 108.027775ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:15:49.365702Z","caller":"traceutil/trace.go:171","msg":"trace[466845131] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"138.199994ms","start":"2026-08-05T11:15:49.227472Z","end":"2026-08-05T11:15:49.365672Z","steps":["trace[466845131] 'process raft request' (duration: 109.399433ms)","trace[466845131] 'compare' (duration: 28.685598ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:16:26.182221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2981} {"level":"info","ts":"2026-08-05T11:16:26.253688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2981,"took":"69.997097ms","hash":1110053046,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":34205696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-05T11:16:26.253768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1110053046,"revision":2981,"compact-revision":-1} {"level":"info","ts":"2026-08-05T11:20:53.394235Z","caller":"traceutil/trace.go:171","msg":"trace[1573000463] linearizableReadLoop","detail":"{readStateIndex:8401; appliedIndex:8399; }","duration":"360.043595ms","start":"2026-08-05T11:20:53.034171Z","end":"2026-08-05T11:20:53.394215Z","steps":["trace[1573000463] 'read index received' (duration: 16.031186ms)","trace[1573000463] 'applied index is now lower than readState.Index' (duration: 344.011469ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:20:53.394302Z","caller":"traceutil/trace.go:171","msg":"trace[1376702107] transaction","detail":"{read_only:false; response_revision:8122; number_of_response:1; }","duration":"361.614549ms","start":"2026-08-05T11:20:53.032655Z","end":"2026-08-05T11:20:53.39427Z","steps":["trace[1376702107] 'process raft request' (duration: 361.416873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:20:53.394447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.268481ms","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-08-05T11:20:53.394482Z","caller":"traceutil/trace.go:171","msg":"trace[2038996059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8122; }","duration":"360.321652ms","start":"2026-08-05T11:20:53.034151Z","end":"2026-08-05T11:20:53.394473Z","steps":["trace[2038996059] 'agreement among raft nodes before linearized reading' (duration: 360.184959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:20:53.394506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:20:53.032641Z","time spent":"361.742912ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:20:53.394517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:20:53.034079Z","time spent":"360.426895ms","remote":"127.0.0.1:37684","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-08-05T11:20:53.402249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.765697ms","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-08-05T11:20:53.40234Z","caller":"traceutil/trace.go:171","msg":"trace[2143460516] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8123; }","duration":"208.87029ms","start":"2026-08-05T11:20:53.193448Z","end":"2026-08-05T11:20:53.402318Z","steps":["trace[2143460516] 'agreement among raft nodes before linearized reading' (duration: 208.621113ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:21:26.192852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6129} {"level":"info","ts":"2026-08-05T11:21:26.282573Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6129,"took":"88.205604ms","hash":1489399049,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":23306240,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-05T11:21:26.282686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1489399049,"revision":6129,"compact-revision":2981} {"level":"warn","ts":"2026-08-05T11:21:34.955971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.793675ms","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-08-05T11:21:34.956141Z","caller":"traceutil/trace.go:171","msg":"trace[13675640] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8438; }","duration":"318.047202ms","start":"2026-08-05T11:21:34.638075Z","end":"2026-08-05T11:21:34.956123Z","steps":["trace[13675640] 'count revisions from in-memory index tree' (duration: 317.677692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:21:34.956189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:21:34.638051Z","time spent":"318.120954ms","remote":"127.0.0.1:60646","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":"warn","ts":"2026-08-05T11:21:34.98151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.913311ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-05T11:21:34.982264Z","caller":"traceutil/trace.go:171","msg":"trace[1106493204] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"264.779969ms","start":"2026-08-05T11:21:34.717468Z","end":"2026-08-05T11:21:34.982248Z","steps":["trace[1106493204] 'process raft request' (duration: 264.423919ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:21:34.982303Z","caller":"traceutil/trace.go:171","msg":"trace[558437934] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"294.284931ms","start":"2026-08-05T11:21:34.687981Z","end":"2026-08-05T11:21:34.982266Z","steps":["trace[558437934] 'process raft request' (duration: 293.603642ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:21:34.982673Z","caller":"traceutil/trace.go:171","msg":"trace[24989210] linearizableReadLoop","detail":"{readStateIndex:8734; appliedIndex:8732; }","duration":"265.705025ms","start":"2026-08-05T11:21:34.716925Z","end":"2026-08-05T11:21:34.98263Z","steps":["trace[24989210] 'read index received' (duration: 22.48µs)","trace[24989210] 'applied index is now lower than readState.Index' (duration: 265.677805ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:21:34.982916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.966922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-4766x\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-08-05T11:21:34.982975Z","caller":"traceutil/trace.go:171","msg":"trace[2001453188] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-4766x; range_end:; response_count:1; response_revision:8441; }","duration":"266.069205ms","start":"2026-08-05T11:21:34.716887Z","end":"2026-08-05T11:21:34.982956Z","steps":["trace[2001453188] 'agreement among raft nodes before linearized reading' (duration: 265.865459ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:21:35.740199Z","caller":"traceutil/trace.go:171","msg":"trace[1430509921] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"277.946356ms","start":"2026-08-05T11:21:35.462226Z","end":"2026-08-05T11:21:35.740173Z","steps":["trace[1430509921] 'process raft request' (duration: 277.777331ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:21:35.755987Z","caller":"traceutil/trace.go:171","msg":"trace[284260252] linearizableReadLoop","detail":"{readStateIndex:8741; appliedIndex:8739; }","duration":"169.748495ms","start":"2026-08-05T11:21:35.586212Z","end":"2026-08-05T11:21:35.755961Z","steps":["trace[284260252] 'read index received' (duration: 153.77752ms)","trace[284260252] 'applied index is now lower than readState.Index' (duration: 15.969375ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:21:35.756044Z","caller":"traceutil/trace.go:171","msg":"trace[1496156799] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"203.226186ms","start":"2026-08-05T11:21:35.552791Z","end":"2026-08-05T11:21:35.756017Z","steps":["trace[1496156799] 'process raft request' (duration: 202.975289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:21:35.75628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.039203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-05T11:21:35.756342Z","caller":"traceutil/trace.go:171","msg":"trace[1727845173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8447; }","duration":"170.155056ms","start":"2026-08-05T11:21:35.586168Z","end":"2026-08-05T11:21:35.756323Z","steps":["trace[1727845173] 'agreement among raft nodes before linearized reading' (duration: 169.93088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:21:35.756343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.376376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-05T11:21:35.756365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.083349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-05T11:21:35.756475Z","caller":"traceutil/trace.go:171","msg":"trace[1164021050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8447; }","duration":"163.281185ms","start":"2026-08-05T11:21:35.593181Z","end":"2026-08-05T11:21:35.756462Z","steps":["trace[1164021050] 'agreement among raft nodes before linearized reading' (duration: 162.984886ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:21:35.756406Z","caller":"traceutil/trace.go:171","msg":"trace[1243586301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8447; }","duration":"122.468089ms","start":"2026-08-05T11:21:35.633922Z","end":"2026-08-05T11:21:35.75639Z","steps":["trace[1243586301] 'agreement among raft nodes before linearized reading' (duration: 122.309894ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:22:42.830961Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b7.wal"} {"level":"info","ts":"2026-08-05T11:22:46.309565Z","caller":"traceutil/trace.go:171","msg":"trace[87779487] linearizableReadLoop","detail":"{readStateIndex:9418; appliedIndex:9417; }","duration":"183.068135ms","start":"2026-08-05T11:22:46.126474Z","end":"2026-08-05T11:22:46.309542Z","steps":["trace[87779487] 'read index received' (duration: 182.89998ms)","trace[87779487] 'applied index is now lower than readState.Index' (duration: 167.035µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:22:46.309672Z","caller":"traceutil/trace.go:171","msg":"trace[1599755333] transaction","detail":"{read_only:false; response_revision:9103; number_of_response:1; }","duration":"207.949098ms","start":"2026-08-05T11:22:46.101712Z","end":"2026-08-05T11:22:46.309661Z","steps":["trace[1599755333] 'process raft request' (duration: 207.692371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:46.309883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.399905ms","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-08-05T11:22:46.309923Z","caller":"traceutil/trace.go:171","msg":"trace[485661809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9103; }","duration":"183.489377ms","start":"2026-08-05T11:22:46.126423Z","end":"2026-08-05T11:22:46.309912Z","steps":["trace[485661809] 'agreement among raft nodes before linearized reading' (duration: 183.338763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:46.324747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.766701ms","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-08-05T11:22:46.324801Z","caller":"traceutil/trace.go:171","msg":"trace[416179644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9104; }","duration":"194.869014ms","start":"2026-08-05T11:22:46.129919Z","end":"2026-08-05T11:22:46.324788Z","steps":["trace[416179644] 'agreement among raft nodes before linearized reading' (duration: 194.644167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:46.325217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.655207ms","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-08-05T11:22:46.325257Z","caller":"traceutil/trace.go:171","msg":"trace[1441354582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9104; }","duration":"194.733799ms","start":"2026-08-05T11:22:46.130514Z","end":"2026-08-05T11:22:46.325248Z","steps":["trace[1441354582] 'agreement among raft nodes before linearized reading' (duration: 194.628617ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:53.906835Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T11:23:53.912006Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T11:23:53.912233Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T11:26:26.200206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8353} {"level":"info","ts":"2026-08-05T11:26:26.28197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8353,"took":"78.969741ms","hash":3645661279,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":26521600,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-05T11:26:26.282051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3645661279,"revision":8353,"compact-revision":6129} {"level":"info","ts":"2026-08-05T11:26:29.188234Z","caller":"traceutil/trace.go:171","msg":"trace[777200118] transaction","detail":"{read_only:false; response_revision:11040; number_of_response:1; }","duration":"126.043282ms","start":"2026-08-05T11:26:29.06217Z","end":"2026-08-05T11:26:29.188213Z","steps":["trace[777200118] 'process raft request' (duration: 125.917318ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:31:26.206377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11026} {"level":"info","ts":"2026-08-05T11:31:26.286355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11026,"took":"78.013882ms","hash":3612723277,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":27893760,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-05T11:31:26.286498Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3612723277,"revision":11026,"compact-revision":8353} {"level":"info","ts":"2026-08-05T11:32:35.275495Z","caller":"traceutil/trace.go:171","msg":"trace[1948472403] transaction","detail":"{read_only:false; response_revision:13522; number_of_response:1; }","duration":"103.874451ms","start":"2026-08-05T11:32:35.1716Z","end":"2026-08-05T11:32:35.275475Z","steps":["trace[1948472403] 'process raft request' (duration: 103.394567ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:32:35.275628Z","caller":"traceutil/trace.go:171","msg":"trace[680704914] transaction","detail":"{read_only:false; response_revision:13523; number_of_response:1; }","duration":"103.672726ms","start":"2026-08-05T11:32:35.171947Z","end":"2026-08-05T11:32:35.27562Z","steps":["trace[680704914] 'process raft request' (duration: 103.142991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:32:49.529098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.057764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-05T11:32:49.529079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.04279ms","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-08-05T11:32:49.52919Z","caller":"traceutil/trace.go:171","msg":"trace[1264766817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13609; }","duration":"198.185748ms","start":"2026-08-05T11:32:49.330986Z","end":"2026-08-05T11:32:49.529172Z","steps":["trace[1264766817] 'range keys from in-memory index tree' (duration: 197.900871ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:32:49.529235Z","caller":"traceutil/trace.go:171","msg":"trace[189017215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13609; }","duration":"195.255507ms","start":"2026-08-05T11:32:49.333947Z","end":"2026-08-05T11:32:49.529202Z","steps":["trace[189017215] 'range keys from in-memory index tree' (duration: 194.774313ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:32:49.529236Z","caller":"traceutil/trace.go:171","msg":"trace[579915617] transaction","detail":"{read_only:false; response_revision:13610; number_of_response:1; }","duration":"185.411593ms","start":"2026-08-05T11:32:49.343791Z","end":"2026-08-05T11:32:49.529202Z","steps":["trace[579915617] 'process raft request' (duration: 183.211031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:32:49.52908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.821836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-05T11:32:49.529323Z","caller":"traceutil/trace.go:171","msg":"trace[2134740368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13609; }","duration":"194.097815ms","start":"2026-08-05T11:32:49.335211Z","end":"2026-08-05T11:32:49.529308Z","steps":["trace[2134740368] 'range keys from in-memory index tree' (duration: 193.643761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:32:49.529369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.393104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:32:49.529433Z","caller":"traceutil/trace.go:171","msg":"trace[1097440044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13610; }","duration":"161.501777ms","start":"2026-08-05T11:32:49.367918Z","end":"2026-08-05T11:32:49.52942Z","steps":["trace[1097440044] 'agreement among raft nodes before linearized reading' (duration: 161.358823ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:32:49.52919Z","caller":"traceutil/trace.go:171","msg":"trace[1449179162] linearizableReadLoop","detail":"{readStateIndex:14074; appliedIndex:14073; }","duration":"161.194238ms","start":"2026-08-05T11:32:49.367958Z","end":"2026-08-05T11:32:49.529152Z","steps":["trace[1449179162] 'read index received' (duration: 158.966906ms)","trace[1449179162] 'applied index is now lower than readState.Index' (duration: 2.225082ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:32:50.067321Z","caller":"traceutil/trace.go:171","msg":"trace[1771186960] transaction","detail":"{read_only:false; response_revision:13614; number_of_response:1; }","duration":"171.534496ms","start":"2026-08-05T11:32:49.895755Z","end":"2026-08-05T11:32:50.067289Z","steps":["trace[1771186960] 'process raft request' (duration: 171.343451ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:32:50.396348Z","caller":"traceutil/trace.go:171","msg":"trace[967847861] linearizableReadLoop","detail":"{readStateIndex:14079; appliedIndex:14078; }","duration":"263.742823ms","start":"2026-08-05T11:32:50.132581Z","end":"2026-08-05T11:32:50.396324Z","steps":["trace[967847861] 'read index received' (duration: 258.983371ms)","trace[967847861] 'applied index is now lower than readState.Index' (duration: 4.758252ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:32:50.396719Z","caller":"traceutil/trace.go:171","msg":"trace[1200010733] transaction","detail":"{read_only:false; response_revision:13615; number_of_response:1; }","duration":"298.714717ms","start":"2026-08-05T11:32:50.097968Z","end":"2026-08-05T11:32:50.396683Z","steps":["trace[1200010733] 'process raft request' (duration: 293.689307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:32:50.396837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.235537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-wbdgf\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-08-05T11:32:50.396889Z","caller":"traceutil/trace.go:171","msg":"trace[1623743438] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-wbdgf; range_end:; response_count:1; response_revision:13615; }","duration":"264.325ms","start":"2026-08-05T11:32:50.132549Z","end":"2026-08-05T11:32:50.396874Z","steps":["trace[1623743438] 'agreement among raft nodes before linearized reading' (duration: 263.862127ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:32:58.674844Z","caller":"traceutil/trace.go:171","msg":"trace[789087242] linearizableReadLoop","detail":"{readStateIndex:14234; appliedIndex:14233; }","duration":"178.535321ms","start":"2026-08-05T11:32:58.496272Z","end":"2026-08-05T11:32:58.674807Z","steps":["trace[789087242] 'read index received' (duration: 178.348175ms)","trace[789087242] 'applied index is now lower than readState.Index' (duration: 185.666µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:32:58.674962Z","caller":"traceutil/trace.go:171","msg":"trace[1557475212] transaction","detail":"{read_only:false; response_revision:13767; number_of_response:1; }","duration":"199.609438ms","start":"2026-08-05T11:32:58.475313Z","end":"2026-08-05T11:32:58.674922Z","steps":["trace[1557475212] 'process raft request' (duration: 199.306099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:32:58.675047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.755967ms","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-08-05T11:32:58.675159Z","caller":"traceutil/trace.go:171","msg":"trace[427351543] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:13767; }","duration":"178.920472ms","start":"2026-08-05T11:32:58.496221Z","end":"2026-08-05T11:32:58.675141Z","steps":["trace[427351543] 'agreement among raft nodes before linearized reading' (duration: 178.747687ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:32:58.691799Z","caller":"traceutil/trace.go:171","msg":"trace[262511263] transaction","detail":"{read_only:false; response_revision:13769; number_of_response:1; }","duration":"106.400493ms","start":"2026-08-05T11:32:58.585357Z","end":"2026-08-05T11:32:58.691757Z","steps":["trace[262511263] 'process raft request' (duration: 106.282699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:32:58.691917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.714059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T11:32:58.692038Z","caller":"traceutil/trace.go:171","msg":"trace[1229580537] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:13769; }","duration":"177.888423ms","start":"2026-08-05T11:32:58.514113Z","end":"2026-08-05T11:32:58.692001Z","steps":["trace[1229580537] 'agreement among raft nodes before linearized reading' (duration: 177.576845ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:32:58.692089Z","caller":"traceutil/trace.go:171","msg":"trace[411964037] transaction","detail":"{read_only:false; response_revision:13768; number_of_response:1; }","duration":"185.776972ms","start":"2026-08-05T11:32:58.506292Z","end":"2026-08-05T11:32:58.692069Z","steps":["trace[411964037] 'process raft request' (duration: 185.199216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:34:37.475958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.65511ms","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-08-05T11:34:37.476057Z","caller":"traceutil/trace.go:171","msg":"trace[1017633046] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14846; }","duration":"106.796914ms","start":"2026-08-05T11:34:37.369238Z","end":"2026-08-05T11:34:37.476035Z","steps":["trace[1017633046] 'range keys from in-memory index tree' (duration: 106.470375ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:26.21378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13060} {"level":"info","ts":"2026-08-05T11:36:26.291168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13060,"took":"75.198524ms","hash":2525483784,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":31043584,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T11:36:26.291226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2525483784,"revision":13060,"compact-revision":11026} {"level":"info","ts":"2026-08-05T11:37:18.937045Z","caller":"traceutil/trace.go:171","msg":"trace[734974632] transaction","detail":"{read_only:false; response_revision:16143; number_of_response:1; }","duration":"116.34873ms","start":"2026-08-05T11:37:18.820658Z","end":"2026-08-05T11:37:18.937006Z","steps":["trace[734974632] 'process raft request' (duration: 26.909009ms)","trace[734974632] 'compare' (duration: 89.193805ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:37:45.042164Z","caller":"traceutil/trace.go:171","msg":"trace[1407529723] transaction","detail":"{read_only:false; response_revision:16307; number_of_response:1; }","duration":"101.63965ms","start":"2026-08-05T11:37:44.940488Z","end":"2026-08-05T11:37:45.042128Z","steps":["trace[1407529723] 'process raft request' (duration: 101.26134ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:40:20.427316Z","caller":"traceutil/trace.go:171","msg":"trace[1759600795] transaction","detail":"{read_only:false; response_revision:17584; number_of_response:1; }","duration":"129.733084ms","start":"2026-08-05T11:40:20.297551Z","end":"2026-08-05T11:40:20.427284Z","steps":["trace[1759600795] 'process raft request' (duration: 67.084039ms)","trace[1759600795] 'compare' (duration: 62.523092ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:40:20.825997Z","caller":"traceutil/trace.go:171","msg":"trace[271374684] linearizableReadLoop","detail":"{readStateIndex:18169; appliedIndex:18168; }","duration":"327.935115ms","start":"2026-08-05T11:40:20.498039Z","end":"2026-08-05T11:40:20.825974Z","steps":["trace[271374684] 'read index received' (duration: 327.660257ms)","trace[271374684] 'applied index is now lower than readState.Index' (duration: 273.698µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:40:20.826146Z","caller":"traceutil/trace.go:171","msg":"trace[1569200576] transaction","detail":"{read_only:false; response_revision:17585; number_of_response:1; }","duration":"372.888557ms","start":"2026-08-05T11:40:20.45323Z","end":"2026-08-05T11:40:20.826118Z","steps":["trace[1569200576] 'process raft request' (duration: 372.530027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:40:20.826205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.14613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:40:20.826259Z","caller":"traceutil/trace.go:171","msg":"trace[1788304032] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:17585; }","duration":"328.252063ms","start":"2026-08-05T11:40:20.497988Z","end":"2026-08-05T11:40:20.82624Z","steps":["trace[1788304032] 'agreement among raft nodes before linearized reading' (duration: 328.13499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:40:20.826255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.252064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T11:40:20.826292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:40:20.453206Z","time spent":"373.00891ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7169,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:40:20.826312Z","caller":"traceutil/trace.go:171","msg":"trace[1784254972] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:17585; }","duration":"323.312196ms","start":"2026-08-05T11:40:20.502985Z","end":"2026-08-05T11:40:20.826298Z","steps":["trace[1784254972] 'agreement among raft nodes before linearized reading' (duration: 323.217593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:40:20.826345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:40:20.50294Z","time spent":"323.398508ms","remote":"127.0.0.1:37656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-08-05T11:40:20.826375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.201282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:40:20.826414Z","caller":"traceutil/trace.go:171","msg":"trace[385901902] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:17585; }","duration":"323.251304ms","start":"2026-08-05T11:40:20.50315Z","end":"2026-08-05T11:40:20.826402Z","steps":["trace[385901902] 'agreement among raft nodes before linearized reading' (duration: 323.192613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:40:20.826453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:40:20.503142Z","time spent":"323.301576ms","remote":"127.0.0.1:37656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"warn","ts":"2026-08-05T11:40:20.826306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:40:20.497963Z","time spent":"328.332545ms","remote":"127.0.0.1:37656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"warn","ts":"2026-08-05T11:40:20.826543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.523081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T11:40:20.826522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.316276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:40:20.826574Z","caller":"traceutil/trace.go:171","msg":"trace[1852184962] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:17585; }","duration":"323.561533ms","start":"2026-08-05T11:40:20.503002Z","end":"2026-08-05T11:40:20.826564Z","steps":["trace[1852184962] 'agreement among raft nodes before linearized reading' (duration: 323.516662ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:40:20.826591Z","caller":"traceutil/trace.go:171","msg":"trace[410205849] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:17585; }","duration":"323.444309ms","start":"2026-08-05T11:40:20.50313Z","end":"2026-08-05T11:40:20.826574Z","steps":["trace[410205849] 'agreement among raft nodes before linearized reading' (duration: 323.287175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:40:20.8266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:40:20.502997Z","time spent":"323.597704ms","remote":"127.0.0.1:37656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"warn","ts":"2026-08-05T11:40:20.826626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:40:20.503125Z","time spent":"323.49203ms","remote":"127.0.0.1:37656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-08-05T11:40:20.826751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.569313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:40:20.826805Z","caller":"traceutil/trace.go:171","msg":"trace[798143246] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:17585; }","duration":"323.627624ms","start":"2026-08-05T11:40:20.503158Z","end":"2026-08-05T11:40:20.826785Z","steps":["trace[798143246] 'agreement among raft nodes before linearized reading' (duration: 323.545562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:40:20.826855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:40:20.503153Z","time spent":"323.687416ms","remote":"127.0.0.1:37656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"info","ts":"2026-08-05T11:40:22.849113Z","caller":"traceutil/trace.go:171","msg":"trace[975321729] linearizableReadLoop","detail":"{readStateIndex:18190; appliedIndex:18189; }","duration":"112.00623ms","start":"2026-08-05T11:40:22.737081Z","end":"2026-08-05T11:40:22.849088Z","steps":["trace[975321729] 'read index received' (duration: 111.795284ms)","trace[975321729] 'applied index is now lower than readState.Index' (duration: 210.186µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:40:22.84921Z","caller":"traceutil/trace.go:171","msg":"trace[1890301405] transaction","detail":"{read_only:false; response_revision:17606; number_of_response:1; }","duration":"125.809985ms","start":"2026-08-05T11:40:22.723334Z","end":"2026-08-05T11:40:22.849144Z","steps":["trace[1890301405] 'process raft request' (duration: 125.597349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:40:22.849338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.227927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:40:22.849403Z","caller":"traceutil/trace.go:171","msg":"trace[1419171189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:17606; }","duration":"112.34749ms","start":"2026-08-05T11:40:22.737038Z","end":"2026-08-05T11:40:22.849386Z","steps":["trace[1419171189] 'agreement among raft nodes before linearized reading' (duration: 112.200585ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:41:26.220126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15617} {"level":"info","ts":"2026-08-05T11:41:26.314766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15617,"took":"92.037003ms","hash":4113316660,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":34222080,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-05T11:41:26.314822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4113316660,"revision":15617,"compact-revision":13060} {"level":"warn","ts":"2026-08-05T11:42:26.706459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204095073975722,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T11:42:27.69927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204095073975722,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T11:42:28.200135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204095073975722,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T11:42:28.369304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001105635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-05T11:42:28.37996Z","caller":"traceutil/trace.go:171","msg":"trace[2070317946] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.011802693s","start":"2026-08-05T11:42:26.368137Z","end":"2026-08-05T11:42:28.37994Z","steps":["trace[2070317946] 'agreement among raft nodes before linearized reading' (duration: 2.001129516s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.380003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.36812Z","time spent":"2.011872555s","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:42:28.632492Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.922456881s","expected-duration":"1s"} {"level":"info","ts":"2026-08-05T11:42:28.633081Z","caller":"traceutil/trace.go:171","msg":"trace[707998819] transaction","detail":"{read_only:false; response_revision:18574; number_of_response:1; }","duration":"2.92313495s","start":"2026-08-05T11:42:25.709925Z","end":"2026-08-05T11:42:28.633059Z","steps":["trace[707998819] 'process raft request' (duration: 2.922975465s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.633224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:25.709902Z","time spent":"2.923247463s","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:42:28.637896Z","caller":"traceutil/trace.go:171","msg":"trace[282960874] transaction","detail":"{read_only:false; response_revision:18575; number_of_response:1; }","duration":"2.144086288s","start":"2026-08-05T11:42:26.493766Z","end":"2026-08-05T11:42:28.637853Z","steps":["trace[282960874] 'process raft request' (duration: 2.143754989s)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:42:28.637922Z","caller":"traceutil/trace.go:171","msg":"trace[1907407765] transaction","detail":"{read_only:false; response_revision:18576; number_of_response:1; }","duration":"938.463413ms","start":"2026-08-05T11:42:27.699419Z","end":"2026-08-05T11:42:28.637883Z","steps":["trace[1907407765] 'process raft request' (duration: 938.315899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.638106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.699408Z","time spent":"938.623957ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:42:28.638111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.493748Z","time spent":"2.144277933s","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:42:28.637895Z","caller":"traceutil/trace.go:171","msg":"trace[641012088] linearizableReadLoop","detail":"{readStateIndex:19194; appliedIndex:19191; }","duration":"2.431597397s","start":"2026-08-05T11:42:26.206187Z","end":"2026-08-05T11:42:28.637784Z","steps":["trace[641012088] 'read index received' (duration: 2.426694331s)","trace[641012088] 'applied index is now lower than readState.Index' (duration: 4.901666ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:42:28.639602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.433356636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-05T11:42:28.642204Z","caller":"traceutil/trace.go:171","msg":"trace[589971214] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18576; }","duration":"2.43600856s","start":"2026-08-05T11:42:26.206151Z","end":"2026-08-05T11:42:28.64216Z","steps":["trace[589971214] 'agreement among raft nodes before linearized reading' (duration: 2.431824013s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.642309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.206126Z","time spent":"2.436142524s","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T11:42:28.642562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.435428064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-05T11:42:28.642647Z","caller":"traceutil/trace.go:171","msg":"trace[634640830] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18576; }","duration":"2.435543297s","start":"2026-08-05T11:42:26.207082Z","end":"2026-08-05T11:42:28.642625Z","steps":["trace[634640830] 'agreement among raft nodes before linearized reading' (duration: 2.43528393s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.642665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.521924ms","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:6"} {"level":"warn","ts":"2026-08-05T11:42:28.642687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.207073Z","time spent":"2.43560614s","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-05T11:42:28.6427Z","caller":"traceutil/trace.go:171","msg":"trace[1573224306] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:18576; }","duration":"943.567295ms","start":"2026-08-05T11:42:27.699123Z","end":"2026-08-05T11:42:28.642691Z","steps":["trace[1573224306] 'agreement among raft nodes before linearized reading' (duration: 943.496413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.642725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.699114Z","time spent":"943.605236ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:42:28.642858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.276806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:42:28.642882Z","caller":"traceutil/trace.go:171","msg":"trace[1354882895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18576; }","duration":"262.335668ms","start":"2026-08-05T11:42:28.38054Z","end":"2026-08-05T11:42:28.642876Z","steps":["trace[1354882895] 'agreement among raft nodes before linearized reading' (duration: 262.292197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.642992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.909134293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-05T11:42:28.643042Z","caller":"traceutil/trace.go:171","msg":"trace[1400041489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18576; }","duration":"1.909232606s","start":"2026-08-05T11:42:26.733793Z","end":"2026-08-05T11:42:28.643025Z","steps":["trace[1400041489] 'agreement among raft nodes before linearized reading' (duration: 1.909118283s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.643092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.733756Z","time spent":"1.909323819s","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-05T11:42:28.643045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.899606218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-05T11:42:28.643149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.593365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:122352"} {"level":"info","ts":"2026-08-05T11:42:28.643172Z","caller":"traceutil/trace.go:171","msg":"trace[392060877] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:18576; }","duration":"943.655367ms","start":"2026-08-05T11:42:27.69951Z","end":"2026-08-05T11:42:28.643165Z","steps":["trace[392060877] 'agreement among raft nodes before linearized reading' (duration: 943.503813ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:42:28.643165Z","caller":"traceutil/trace.go:171","msg":"trace[1129167348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18576; }","duration":"1.899778533s","start":"2026-08-05T11:42:26.74337Z","end":"2026-08-05T11:42:28.643148Z","steps":["trace[1129167348] 'agreement among raft nodes before linearized reading' (duration: 1.899398132s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.643189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.699464Z","time spent":"943.720409ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":122375,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-08-05T11:42:28.643216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.743349Z","time spent":"1.899857225s","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T11:42:28.643377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029921158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:1 size:7467"} {"level":"warn","ts":"2026-08-05T11:42:28.643406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.299906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-08-05T11:42:28.643429Z","caller":"traceutil/trace.go:171","msg":"trace[424693571] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:18576; }","duration":"944.344326ms","start":"2026-08-05T11:42:27.699078Z","end":"2026-08-05T11:42:28.643422Z","steps":["trace[424693571] 'agreement among raft nodes before linearized reading' (duration: 944.271464ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:42:28.643426Z","caller":"traceutil/trace.go:171","msg":"trace[1681300465] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:1; response_revision:18576; }","duration":"2.0299859s","start":"2026-08-05T11:42:26.613426Z","end":"2026-08-05T11:42:28.643412Z","steps":["trace[1681300465] 'agreement among raft nodes before linearized reading' (duration: 2.029852446s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.643457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.11033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-08-05T11:42:28.643479Z","caller":"traceutil/trace.go:171","msg":"trace[1201154847] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:18576; }","duration":"944.170122ms","start":"2026-08-05T11:42:27.699302Z","end":"2026-08-05T11:42:28.643473Z","steps":["trace[1201154847] 'agreement among raft nodes before linearized reading' (duration: 944.10227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.643496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.699286Z","time spent":"944.204292ms","remote":"127.0.0.1:37556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1589,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" "} {"level":"warn","ts":"2026-08-05T11:42:28.643498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.613418Z","time spent":"2.030050352s","remote":"127.0.0.1:37656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":7490,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-08-05T11:42:28.643519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.47084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:1 size:7467"} {"level":"info","ts":"2026-08-05T11:42:28.64354Z","caller":"traceutil/trace.go:171","msg":"trace[1371750813] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:1; response_revision:18576; }","duration":"944.529911ms","start":"2026-08-05T11:42:27.699004Z","end":"2026-08-05T11:42:28.643534Z","steps":["trace[1371750813] 'agreement among raft nodes before linearized reading' (duration: 944.455559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.643556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.698989Z","time spent":"944.562373ms","remote":"127.0.0.1:37656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":7490,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-08-05T11:42:28.64364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.319926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-05T11:42:28.643662Z","caller":"traceutil/trace.go:171","msg":"trace[2111371698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18576; }","duration":"944.349057ms","start":"2026-08-05T11:42:27.699307Z","end":"2026-08-05T11:42:28.643656Z","steps":["trace[2111371698] 'agreement among raft nodes before linearized reading' (duration: 944.285145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.643679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.699302Z","time spent":"944.372867ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T11:42:28.643849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812355527s","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:8"} {"level":"warn","ts":"2026-08-05T11:42:28.643881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.031027159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:1 size:7467"} {"level":"info","ts":"2026-08-05T11:42:28.643947Z","caller":"traceutil/trace.go:171","msg":"trace[74072503] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:1; response_revision:18576; }","duration":"2.031127692s","start":"2026-08-05T11:42:26.612805Z","end":"2026-08-05T11:42:28.643933Z","steps":["trace[74072503] 'agreement among raft nodes before linearized reading' (duration: 2.030961897s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.643992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.612789Z","time spent":"2.031192914s","remote":"127.0.0.1:37656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":7490,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-08-05T11:42:28.643392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.961276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:42:28.644087Z","caller":"traceutil/trace.go:171","msg":"trace[629189855] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:18576; }","duration":"944.669045ms","start":"2026-08-05T11:42:27.699406Z","end":"2026-08-05T11:42:28.644075Z","steps":["trace[629189855] 'agreement among raft nodes before linearized reading' (duration: 943.960796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.644143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.699399Z","time spent":"944.733617ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:42:28.643448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.699064Z","time spent":"944.379608ms","remote":"127.0.0.1:37454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-08-05T11:42:28.644185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.856321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-05T11:42:28.64429Z","caller":"traceutil/trace.go:171","msg":"trace[1426435770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18576; }","duration":"944.975214ms","start":"2026-08-05T11:42:27.699265Z","end":"2026-08-05T11:42:28.64424Z","steps":["trace[1426435770] 'agreement among raft nodes before linearized reading' (duration: 944.638175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.64433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.154301202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-05T11:42:28.644361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.69926Z","time spent":"945.068307ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-05T11:42:28.64438Z","caller":"traceutil/trace.go:171","msg":"trace[2136208236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18576; }","duration":"2.154368424s","start":"2026-08-05T11:42:26.489993Z","end":"2026-08-05T11:42:28.644362Z","steps":["trace[2136208236] 'agreement among raft nodes before linearized reading' (duration: 2.154283522s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.644402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.489976Z","time spent":"2.154420106s","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-05T11:42:28.643002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.034974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:42:28.643879Z","caller":"traceutil/trace.go:171","msg":"trace[1060993576] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18576; }","duration":"1.812391918s","start":"2026-08-05T11:42:26.831478Z","end":"2026-08-05T11:42:28.64387Z","steps":["trace[1060993576] 'agreement among raft nodes before linearized reading' (duration: 1.812141381s)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:42:28.64458Z","caller":"traceutil/trace.go:171","msg":"trace[1685337546] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:18576; }","duration":"382.603879ms","start":"2026-08-05T11:42:28.261941Z","end":"2026-08-05T11:42:28.644545Z","steps":["trace[1685337546] 'agreement among raft nodes before linearized reading' (duration: 381.033065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.644588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.831436Z","time spent":"1.813127638s","remote":"127.0.0.1:60646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:42:28.644614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:28.261927Z","time spent":"382.67811ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"info","ts":"2026-08-05T11:44:09.397241Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T11:44:09.403789Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-05T11:44:09.40401Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-05T11:44:53.499Z","caller":"traceutil/trace.go:171","msg":"trace[2074577048] linearizableReadLoop","detail":"{readStateIndex:20240; appliedIndex:20239; }","duration":"132.751365ms","start":"2026-08-05T11:44:53.366202Z","end":"2026-08-05T11:44:53.498953Z","steps":["trace[2074577048] 'read index received' (duration: 110.495955ms)","trace[2074577048] 'applied index is now lower than readState.Index' (duration: 22.25426ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:44:53.499216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.992962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:44:53.499267Z","caller":"traceutil/trace.go:171","msg":"trace[507910178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19585; }","duration":"133.088814ms","start":"2026-08-05T11:44:53.366165Z","end":"2026-08-05T11:44:53.499254Z","steps":["trace[507910178] 'agreement among raft nodes before linearized reading' (duration: 132.92669ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:44:53.499435Z","caller":"traceutil/trace.go:171","msg":"trace[307230427] transaction","detail":"{read_only:false; response_revision:19585; number_of_response:1; }","duration":"210.226381ms","start":"2026-08-05T11:44:53.289182Z","end":"2026-08-05T11:44:53.499408Z","steps":["trace[307230427] 'process raft request' (duration: 187.5693ms)","trace[307230427] 'compare' (duration: 22.074514ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:03.731748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.748474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T11:45:03.731988Z","caller":"traceutil/trace.go:171","msg":"trace[150085414] transaction","detail":"{read_only:false; response_revision:19651; number_of_response:1; }","duration":"361.343786ms","start":"2026-08-05T11:45:03.370621Z","end":"2026-08-05T11:45:03.731965Z","steps":["trace[150085414] 'process raft request' (duration: 361.255094ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:03.732002Z","caller":"traceutil/trace.go:171","msg":"trace[625584660] transaction","detail":"{read_only:false; response_revision:19650; number_of_response:1; }","duration":"407.435109ms","start":"2026-08-05T11:45:03.324541Z","end":"2026-08-05T11:45:03.731976Z","steps":["trace[625584660] 'process raft request' (duration: 163.352206ms)","trace[625584660] 'compare' (duration: 243.442795ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:03.732095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.370604Z","time spent":"361.44126ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:45:03.732144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.324517Z","time spent":"407.557561ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:45:03.732302Z","caller":"traceutil/trace.go:171","msg":"trace[1615219208] linearizableReadLoop","detail":"{readStateIndex:20307; appliedIndex:20306; }","duration":"363.716553ms","start":"2026-08-05T11:45:03.368549Z","end":"2026-08-05T11:45:03.732265Z","steps":["trace[1615219208] 'read index received' (duration: 119.362872ms)","trace[1615219208] 'applied index is now lower than readState.Index' (duration: 244.351591ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:03.732654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.106594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:03.732716Z","caller":"traceutil/trace.go:171","msg":"trace[1765973255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19651; }","duration":"364.205547ms","start":"2026-08-05T11:45:03.368495Z","end":"2026-08-05T11:45:03.7327Z","steps":["trace[1765973255] 'agreement among raft nodes before linearized reading' (duration: 364.110644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:03.732765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.368472Z","time spent":"364.282408ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:45:03.733306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.206278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T11:45:03.733396Z","caller":"traceutil/trace.go:171","msg":"trace[1594330142] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:19651; }","duration":"211.331362ms","start":"2026-08-05T11:45:03.522048Z","end":"2026-08-05T11:45:03.73338Z","steps":["trace[1594330142] 'agreement among raft nodes before linearized reading' (duration: 210.609462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:04.147049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.886706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T11:45:04.147135Z","caller":"traceutil/trace.go:171","msg":"trace[564834584] transaction","detail":"{read_only:false; response_revision:19652; number_of_response:1; }","duration":"407.550992ms","start":"2026-08-05T11:45:03.739569Z","end":"2026-08-05T11:45:04.14712Z","steps":["trace[564834584] 'process raft request' (duration: 107.521742ms)","trace[564834584] 'compare' (duration: 299.518156ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:04.147208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.739552Z","time spent":"407.604704ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:45:04.614716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.120644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T11:45:04.614927Z","caller":"traceutil/trace.go:171","msg":"trace[2141033625] transaction","detail":"{read_only:false; response_revision:19653; number_of_response:1; }","duration":"875.089744ms","start":"2026-08-05T11:45:03.739814Z","end":"2026-08-05T11:45:04.614903Z","steps":["trace[2141033625] 'process raft request' (duration: 616.713453ms)","trace[2141033625] 'compare' (duration: 257.96102ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:04.615064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.739806Z","time spent":"875.180857ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:45:04.615424Z","caller":"traceutil/trace.go:171","msg":"trace[380136543] transaction","detail":"{read_only:false; response_revision:19655; number_of_response:1; }","duration":"875.085194ms","start":"2026-08-05T11:45:03.740325Z","end":"2026-08-05T11:45:04.61541Z","steps":["trace[380136543] 'process raft request' (duration: 874.551899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:04.61549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.740294Z","time spent":"875.164227ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:45:04.615724Z","caller":"traceutil/trace.go:171","msg":"trace[1916512336] transaction","detail":"{read_only:false; response_revision:19654; number_of_response:1; }","duration":"875.480516ms","start":"2026-08-05T11:45:03.740232Z","end":"2026-08-05T11:45:04.615712Z","steps":["trace[1916512336] 'process raft request' (duration: 874.56343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:04.615804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.740222Z","time spent":"875.536397ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:45:04.616363Z","caller":"traceutil/trace.go:171","msg":"trace[280333256] transaction","detail":"{read_only:false; response_revision:19658; number_of_response:1; }","duration":"803.187643ms","start":"2026-08-05T11:45:03.813158Z","end":"2026-08-05T11:45:04.616345Z","steps":["trace[280333256] 'process raft request' (duration: 801.803935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:04.616442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.81314Z","time spent":"803.264596ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:45:04.616452Z","caller":"traceutil/trace.go:171","msg":"trace[884761089] transaction","detail":"{read_only:false; response_revision:19656; number_of_response:1; }","duration":"875.928537ms","start":"2026-08-05T11:45:03.740494Z","end":"2026-08-05T11:45:04.616423Z","steps":["trace[884761089] 'process raft request' (duration: 874.409965ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:04.616537Z","caller":"traceutil/trace.go:171","msg":"trace[252436988] transaction","detail":"{read_only:false; response_revision:19657; number_of_response:1; }","duration":"875.825534ms","start":"2026-08-05T11:45:03.740453Z","end":"2026-08-05T11:45:04.616278Z","steps":["trace[252436988] 'process raft request' (duration: 874.477377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:04.616623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.740485Z","time spent":"876.049501ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:45:04.616582Z","caller":"traceutil/trace.go:171","msg":"trace[440123091] linearizableReadLoop","detail":"{readStateIndex:20314; appliedIndex:20308; }","duration":"815.069764ms","start":"2026-08-05T11:45:03.801455Z","end":"2026-08-05T11:45:04.616525Z","steps":["trace[440123091] 'read index received' (duration: 45.64143ms)","trace[440123091] 'applied index is now lower than readState.Index' (duration: 769.421944ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:04.617134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.740442Z","time spent":"876.580296ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:45:04.98507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.044209ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-05T11:45:04.985222Z","caller":"traceutil/trace.go:171","msg":"trace[727086988] linearizableReadLoop","detail":"{readStateIndex:20316; appliedIndex:20315; }","duration":"368.526877ms","start":"2026-08-05T11:45:04.616677Z","end":"2026-08-05T11:45:04.985204Z","steps":["trace[727086988] 'read index received' (duration: 65.912µs)","trace[727086988] 'applied index is now lower than readState.Index' (duration: 368.459025ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:04.985476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184003752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:04.985536Z","caller":"traceutil/trace.go:171","msg":"trace[272349822] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:19658; }","duration":"1.184103055s","start":"2026-08-05T11:45:03.801415Z","end":"2026-08-05T11:45:04.985518Z","steps":["trace[272349822] 'agreement among raft nodes before linearized reading' (duration: 815.200688ms)","trace[272349822] 'count revisions from in-memory index tree' (duration: 368.806645ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:04.985572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.801397Z","time spent":"1.184164886s","remote":"127.0.0.1:41114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:45:04.985586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.804835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:04.98563Z","caller":"traceutil/trace.go:171","msg":"trace[398245659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19658; }","duration":"612.893048ms","start":"2026-08-05T11:45:04.372722Z","end":"2026-08-05T11:45:04.985615Z","steps":["trace[398245659] 'agreement among raft nodes before linearized reading' (duration: 612.817515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:04.985669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:04.372687Z","time spent":"612.9727ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:45:04.98584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041285101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-08-05T11:45:04.985886Z","caller":"traceutil/trace.go:171","msg":"trace[969321760] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:19658; }","duration":"1.041390113s","start":"2026-08-05T11:45:03.944481Z","end":"2026-08-05T11:45:04.985871Z","steps":["trace[969321760] 'agreement among raft nodes before linearized reading' (duration: 1.041218808s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:04.985919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.944467Z","time spent":"1.041440505s","remote":"127.0.0.1:37556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-08-05T11:45:04.985982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.247953ms","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:6"} {"level":"warn","ts":"2026-08-05T11:45:04.986037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105469936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:04.986075Z","caller":"traceutil/trace.go:171","msg":"trace[441348449] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:19658; }","duration":"1.105542518s","start":"2026-08-05T11:45:03.880523Z","end":"2026-08-05T11:45:04.986065Z","steps":["trace[441348449] 'agreement among raft nodes before linearized reading' (duration: 1.105474976s)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:04.986085Z","caller":"traceutil/trace.go:171","msg":"trace[1082589192] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:19658; }","duration":"334.464399ms","start":"2026-08-05T11:45:04.651592Z","end":"2026-08-05T11:45:04.986056Z","steps":["trace[1082589192] 'agreement among raft nodes before linearized reading' (duration: 334.15018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:04.986115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:03.880504Z","time spent":"1.10560119s","remote":"127.0.0.1:37484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:45:04.98619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.266962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-05T11:45:04.986256Z","caller":"traceutil/trace.go:171","msg":"trace[551400153] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:19658; }","duration":"164.404576ms","start":"2026-08-05T11:45:04.821836Z","end":"2026-08-05T11:45:04.986241Z","steps":["trace[551400153] 'agreement among raft nodes before linearized reading' (duration: 164.18006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:04.986938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:04.651574Z","time spent":"334.565851ms","remote":"127.0.0.1:59206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-08-05T11:45:08.280365Z","caller":"traceutil/trace.go:171","msg":"trace[488065743] transaction","detail":"{read_only:false; response_revision:19677; number_of_response:1; }","duration":"103.890271ms","start":"2026-08-05T11:45:08.176455Z","end":"2026-08-05T11:45:08.280346Z","steps":["trace[488065743] 'process raft request' (duration: 103.735586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:08.520148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.779444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:08.52023Z","caller":"traceutil/trace.go:171","msg":"trace[1936308873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19677; }","duration":"151.920827ms","start":"2026-08-05T11:45:08.368292Z","end":"2026-08-05T11:45:08.520213Z","steps":["trace[1936308873] 'range keys from in-memory index tree' (duration: 151.698112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:46:01.470603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.422888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T11:46:01.470752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.162079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T11:46:01.470764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.370737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:46:01.470796Z","caller":"traceutil/trace.go:171","msg":"trace[2016903140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20226; }","duration":"104.263822ms","start":"2026-08-05T11:46:01.366516Z","end":"2026-08-05T11:46:01.47078Z","steps":["trace[2016903140] 'range keys from in-memory index tree' (duration: 104.009395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:46:01.470783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.695666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:46:01.470885Z","caller":"traceutil/trace.go:171","msg":"trace[1585986243] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:20226; }","duration":"154.841669ms","start":"2026-08-05T11:46:01.31602Z","end":"2026-08-05T11:46:01.470862Z","steps":["trace[1585986243] 'range keys from in-memory index tree' (duration: 154.623223ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:46:01.470836Z","caller":"traceutil/trace.go:171","msg":"trace[628070675] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:20226; }","duration":"154.48984ms","start":"2026-08-05T11:46:01.316327Z","end":"2026-08-05T11:46:01.470817Z","steps":["trace[628070675] 'range keys from in-memory index tree' (duration: 154.272283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:46:01.470987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.897312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5140"} {"level":"info","ts":"2026-08-05T11:46:01.471078Z","caller":"traceutil/trace.go:171","msg":"trace[519970932] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:20226; }","duration":"150.017695ms","start":"2026-08-05T11:46:01.321037Z","end":"2026-08-05T11:46:01.471055Z","steps":["trace[519970932] 'range keys from in-memory index tree' (duration: 149.649315ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:46:01.471244Z","caller":"traceutil/trace.go:171","msg":"trace[1965746742] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:20226; }","duration":"154.578592ms","start":"2026-08-05T11:46:01.316106Z","end":"2026-08-05T11:46:01.470684Z","steps":["trace[1965746742] 'range keys from in-memory index tree' (duration: 154.335985ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:46:26.22686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18180} {"level":"info","ts":"2026-08-05T11:46:26.351734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18180,"took":"120.621757ms","hash":1582282414,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":29687808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T11:46:26.351799Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1582282414,"revision":18180,"compact-revision":15617} {"level":"warn","ts":"2026-08-05T11:49:09.114463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.598657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T11:49:09.11462Z","caller":"traceutil/trace.go:171","msg":"trace[681928268] transaction","detail":"{read_only:false; response_revision:22133; number_of_response:1; }","duration":"269.704076ms","start":"2026-08-05T11:49:08.844902Z","end":"2026-08-05T11:49:09.114606Z","steps":["trace[681928268] 'process raft request' (duration: 10.864783ms)","trace[681928268] 'compare' (duration: 258.070852ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:50:18.724394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.97044ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:50:18.724487Z","caller":"traceutil/trace.go:171","msg":"trace[1276810419] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:22948; }","duration":"170.074293ms","start":"2026-08-05T11:50:18.5544Z","end":"2026-08-05T11:50:18.724475Z","steps":["trace[1276810419] 'range keys from in-memory index tree' (duration: 169.95744ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:50:18.725208Z","caller":"traceutil/trace.go:171","msg":"trace[2039005577] transaction","detail":"{read_only:false; response_revision:22949; number_of_response:1; }","duration":"256.731265ms","start":"2026-08-05T11:50:18.468462Z","end":"2026-08-05T11:50:18.725193Z","steps":["trace[2039005577] 'process raft request' (duration: 157.323218ms)","trace[2039005577] 'compare' (duration: 98.340048ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:50:18.72521Z","caller":"traceutil/trace.go:171","msg":"trace[880604932] linearizableReadLoop","detail":"{readStateIndex:23700; appliedIndex:23699; }","duration":"193.555947ms","start":"2026-08-05T11:50:18.531631Z","end":"2026-08-05T11:50:18.725187Z","steps":["trace[880604932] 'read index received' (duration: 94.229473ms)","trace[880604932] 'applied index is now lower than readState.Index' (duration: 99.325604ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:50:18.72564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.322123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-05T11:50:18.72567Z","caller":"traceutil/trace.go:171","msg":"trace[304553535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22950; }","duration":"104.390365ms","start":"2026-08-05T11:50:18.621274Z","end":"2026-08-05T11:50:18.725664Z","steps":["trace[304553535] 'agreement among raft nodes before linearized reading' (duration: 104.294603ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:50:18.725927Z","caller":"traceutil/trace.go:171","msg":"trace[1493653263] transaction","detail":"{read_only:false; response_revision:22950; number_of_response:1; }","duration":"117.558601ms","start":"2026-08-05T11:50:18.608362Z","end":"2026-08-05T11:50:18.725921Z","steps":["trace[1493653263] 'process raft request' (duration: 116.739789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:50:18.726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.37161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-05T11:50:18.726014Z","caller":"traceutil/trace.go:171","msg":"trace[733017578] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22950; }","duration":"194.421091ms","start":"2026-08-05T11:50:18.531588Z","end":"2026-08-05T11:50:18.726009Z","steps":["trace[733017578] 'agreement among raft nodes before linearized reading' (duration: 193.715561ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:50:18.896916Z","caller":"traceutil/trace.go:171","msg":"trace[1133029830] linearizableReadLoop","detail":"{readStateIndex:23705; appliedIndex:23702; }","duration":"108.401477ms","start":"2026-08-05T11:50:18.788493Z","end":"2026-08-05T11:50:18.896894Z","steps":["trace[1133029830] 'read index received' (duration: 88.515253ms)","trace[1133029830] 'applied index is now lower than readState.Index' (duration: 19.885384ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:50:18.89704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.5228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:50:18.897072Z","caller":"traceutil/trace.go:171","msg":"trace[704977231] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:22954; }","duration":"108.606792ms","start":"2026-08-05T11:50:18.788458Z","end":"2026-08-05T11:50:18.897064Z","steps":["trace[704977231] 'agreement among raft nodes before linearized reading' (duration: 108.52357ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:50:18.897258Z","caller":"traceutil/trace.go:171","msg":"trace[303914918] transaction","detail":"{read_only:false; response_revision:22953; number_of_response:1; }","duration":"165.28333ms","start":"2026-08-05T11:50:18.731946Z","end":"2026-08-05T11:50:18.897229Z","steps":["trace[303914918] 'process raft request' (duration: 164.819327ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:50:18.89733Z","caller":"traceutil/trace.go:171","msg":"trace[2135820929] transaction","detail":"{read_only:false; response_revision:22954; number_of_response:1; }","duration":"165.28607ms","start":"2026-08-05T11:50:18.732032Z","end":"2026-08-05T11:50:18.897318Z","steps":["trace[2135820929] 'process raft request' (duration: 164.819357ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:50:18.89749Z","caller":"traceutil/trace.go:171","msg":"trace[539164004] transaction","detail":"{read_only:false; response_revision:22952; number_of_response:1; }","duration":"165.606069ms","start":"2026-08-05T11:50:18.731873Z","end":"2026-08-05T11:50:18.897479Z","steps":["trace[539164004] 'process raft request' (duration: 145.194321ms)","trace[539164004] 'compare' (duration: 19.40607ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:50:46.556812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.851778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-08-05T11:50:46.556958Z","caller":"traceutil/trace.go:171","msg":"trace[941580216] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23170; }","duration":"151.104216ms","start":"2026-08-05T11:50:46.40582Z","end":"2026-08-05T11:50:46.556925Z","steps":["trace[941580216] 'range keys from in-memory index tree' (duration: 150.580981ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:51:26.254305Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20358} {"level":"info","ts":"2026-08-05T11:51:26.519539Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20358,"took":"262.485444ms","hash":3871844011,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":43302912,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-05T11:51:26.519609Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3871844011,"revision":20358,"compact-revision":18180} {"level":"info","ts":"2026-08-05T11:56:26.263603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23472} {"level":"info","ts":"2026-08-05T11:56:26.411659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23472,"took":"144.032897ms","hash":3994469034,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":29995008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T11:56:26.411733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3994469034,"revision":23472,"compact-revision":20358}