{"level":"warn","ts":"2026-07-21T05:18:39.103707Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T05:18:39.10387Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.95: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.19.213.95:2380","--initial-cluster=instance=https://199.19.213.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.95:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-21T05:18:39.103973Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T05:18:39.104004Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.95:2380"]} {"level":"info","ts":"2026-07-21T05:18:39.104034Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-21T05:18:39.104712Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.95:2379"]} {"level":"info","ts":"2026-07-21T05:18:39.104818Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.95:2380"],"listen-peer-urls":["https://199.19.213.95:2380"],"advertise-client-urls":["https://199.19.213.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.95:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-21T05:18:39.108787Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.694027ms"} {"level":"info","ts":"2026-07-21T05:18:39.114913Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"371c7c2e52231793","cluster-id":"c115742627eabb7c"} {"level":"info","ts":"2026-07-21T05:18:39.115033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T05:18:39.115088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became follower at term 0"} {"level":"info","ts":"2026-07-21T05:18:39.115131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 371c7c2e52231793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T05:18:39.115164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became follower at term 1"} {"level":"info","ts":"2026-07-21T05:18:39.115233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 switched to configuration voters=(3971185509822306195)"} {"level":"warn","ts":"2026-07-21T05:18:39.121375Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T05:18:39.122752Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T05:18:39.123895Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-21T05:18:39.126122Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"371c7c2e52231793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T05:18:39.12647Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-21T05:18:39.126556Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-21T05:18:39.126565Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-21T05:18:39.127769Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"371c7c2e52231793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T05:18:39.128642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 switched to configuration voters=(3971185509822306195)"} {"level":"info","ts":"2026-07-21T05:18:39.12881Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c115742627eabb7c","local-member-id":"371c7c2e52231793","added-peer-id":"371c7c2e52231793","added-peer-peer-urls":["https://199.19.213.95:2380"]} {"level":"info","ts":"2026-07-21T05:18:39.136017Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-21T05:18:39.136097Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.95:2380"} {"level":"info","ts":"2026-07-21T05:18:39.136147Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.95:2380"} {"level":"info","ts":"2026-07-21T05:18:39.136478Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"371c7c2e52231793","initial-advertise-peer-urls":["https://199.19.213.95:2380"],"listen-peer-urls":["https://199.19.213.95:2380"],"advertise-client-urls":["https://199.19.213.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T05:18:39.136578Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T05:18:39.516214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T05:18:39.51628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T05:18:39.516341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 received MsgPreVoteResp from 371c7c2e52231793 at term 1"} {"level":"info","ts":"2026-07-21T05:18:39.516362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became candidate at term 2"} {"level":"info","ts":"2026-07-21T05:18:39.516413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 received MsgVoteResp from 371c7c2e52231793 at term 2"} {"level":"info","ts":"2026-07-21T05:18:39.516437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became leader at term 2"} {"level":"info","ts":"2026-07-21T05:18:39.516475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 371c7c2e52231793 elected leader 371c7c2e52231793 at term 2"} {"level":"info","ts":"2026-07-21T05:18:39.51934Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:18:39.520715Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"371c7c2e52231793","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.95:2379]}","request-path":"/0/members/371c7c2e52231793/attributes","cluster-id":"c115742627eabb7c","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T05:18:39.520895Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T05:18:39.520926Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T05:18:39.521125Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T05:18:39.52121Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T05:18:39.522194Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c115742627eabb7c","local-member-id":"371c7c2e52231793","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:18:39.522272Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:18:39.522295Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:18:39.522957Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.95:2379"} {"level":"info","ts":"2026-07-21T05:18:39.528919Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T05:19:14.200674Z","caller":"traceutil/trace.go:171","msg":"trace[1938437932] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"194.130447ms","start":"2026-07-21T05:19:14.006525Z","end":"2026-07-21T05:19:14.200655Z","steps":["trace[1938437932] 'process raft request' (duration: 193.987005ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:19:14.54364Z","caller":"traceutil/trace.go:171","msg":"trace[1359609077] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"531.301298ms","start":"2026-07-21T05:19:14.012311Z","end":"2026-07-21T05:19:14.543612Z","steps":["trace[1359609077] 'process raft request' (duration: 531.026904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.544137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.012292Z","time spent":"531.42988ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:19:14.544497Z","caller":"traceutil/trace.go:171","msg":"trace[1731430537] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"532.0774ms","start":"2026-07-21T05:19:14.01237Z","end":"2026-07-21T05:19:14.544448Z","steps":["trace[1731430537] 'process raft request' (duration: 531.812986ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:19:14.544593Z","caller":"traceutil/trace.go:171","msg":"trace[1127814911] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"519.996019ms","start":"2026-07-21T05:19:14.024575Z","end":"2026-07-21T05:19:14.544571Z","steps":["trace[1127814911] 'process raft request' (duration: 519.832206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.544666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.024557Z","time spent":"520.07315ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:19:14.544663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.012366Z","time spent":"532.221162ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:19:14.544767Z","caller":"traceutil/trace.go:171","msg":"trace[1148257865] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"515.021144ms","start":"2026-07-21T05:19:14.029713Z","end":"2026-07-21T05:19:14.544734Z","steps":["trace[1148257865] 'process raft request' (duration: 514.896112ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:19:14.544802Z","caller":"traceutil/trace.go:171","msg":"trace[2056877001] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"520.158731ms","start":"2026-07-21T05:19:14.024632Z","end":"2026-07-21T05:19:14.54479Z","steps":["trace[2056877001] 'process raft request' (duration: 519.867207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.544858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.024628Z","time spent":"520.207412ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:19:14.5449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.0297Z","time spent":"515.137666ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:19:14.544981Z","caller":"traceutil/trace.go:171","msg":"trace[611720602] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"500.73655ms","start":"2026-07-21T05:19:14.044235Z","end":"2026-07-21T05:19:14.544972Z","steps":["trace[611720602] 'process raft request' (duration: 500.446985ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:19:14.545029Z","caller":"traceutil/trace.go:171","msg":"trace[931042785] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"515.108295ms","start":"2026-07-21T05:19:14.029912Z","end":"2026-07-21T05:19:14.54502Z","steps":["trace[931042785] 'process raft request' (duration: 514.73671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.545033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.044212Z","time spent":"500.798461ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:19:14.545024Z","caller":"traceutil/trace.go:171","msg":"trace[671898664] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:494; }","duration":"426.62924ms","start":"2026-07-21T05:19:14.118354Z","end":"2026-07-21T05:19:14.544983Z","steps":["trace[671898664] 'read index received' (duration: 82.195191ms)","trace[671898664] 'applied index is now lower than readState.Index' (duration: 344.433349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:19:14.545075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.029907Z","time spent":"515.148616ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:19:14.545142Z","caller":"traceutil/trace.go:171","msg":"trace[1135603149] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"465.35928ms","start":"2026-07-21T05:19:14.079777Z","end":"2026-07-21T05:19:14.545136Z","steps":["trace[1135603149] 'process raft request' (duration: 465.065626ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:19:14.545166Z","caller":"traceutil/trace.go:171","msg":"trace[466690792] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"500.836161ms","start":"2026-07-21T05:19:14.044311Z","end":"2026-07-21T05:19:14.545147Z","steps":["trace[466690792] 'process raft request' (duration: 500.417665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.545178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.079745Z","time spent":"465.419722ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:19:14.545142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.772512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-21T05:19:14.545218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.044306Z","time spent":"500.887522ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:19:14.545231Z","caller":"traceutil/trace.go:171","msg":"trace[373119584] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"492.65736ms","start":"2026-07-21T05:19:14.052568Z","end":"2026-07-21T05:19:14.545226Z","steps":["trace[373119584] 'process raft request' (duration: 492.221713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.545264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.052552Z","time spent":"492.70084ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:19:14.545216Z","caller":"traceutil/trace.go:171","msg":"trace[239287762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"426.889984ms","start":"2026-07-21T05:19:14.118316Z","end":"2026-07-21T05:19:14.545206Z","steps":["trace[239287762] 'agreement among raft nodes before linearized reading' (duration: 426.761692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.545327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.118299Z","time spent":"427.013316ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T05:19:14.545399Z","caller":"traceutil/trace.go:171","msg":"trace[349502240] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"409.581245ms","start":"2026-07-21T05:19:14.135802Z","end":"2026-07-21T05:19:14.545383Z","steps":["trace[349502240] 'process raft request' (duration: 409.129168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.545606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.13578Z","time spent":"409.662747ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:19:14.554353Z","caller":"traceutil/trace.go:171","msg":"trace[646696389] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"336.872286ms","start":"2026-07-21T05:19:14.217471Z","end":"2026-07-21T05:19:14.554343Z","steps":["trace[646696389] 'process raft request' (duration: 336.795215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.554413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.217458Z","time spent":"336.929397ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:19:14.554604Z","caller":"traceutil/trace.go:171","msg":"trace[1209551721] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"348.254507ms","start":"2026-07-21T05:19:14.206343Z","end":"2026-07-21T05:19:14.554597Z","steps":["trace[1209551721] 'process raft request' (duration: 347.462345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.554637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.206329Z","time spent":"348.292497ms","remote":"127.0.0.1:54244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:19:14.554896Z","caller":"traceutil/trace.go:171","msg":"trace[1167132499] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"347.682268ms","start":"2026-07-21T05:19:14.207192Z","end":"2026-07-21T05:19:14.554874Z","steps":["trace[1167132499] 'process raft request' (duration: 346.766244ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:19:14.554942Z","caller":"traceutil/trace.go:171","msg":"trace[19595473] transaction","detail":"{read_only:false; number_of_response:1; response_revision:493; }","duration":"346.450699ms","start":"2026-07-21T05:19:14.208479Z","end":"2026-07-21T05:19:14.55493Z","steps":["trace[19595473] 'process raft request' (duration: 345.517875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:14.554991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.208461Z","time spent":"346.50055ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:19:14.554999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:14.207184Z","time spent":"347.757129ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:19:21.234117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.228114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:19:21.234279Z","caller":"traceutil/trace.go:171","msg":"trace[465011293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"392.428348ms","start":"2026-07-21T05:19:20.841823Z","end":"2026-07-21T05:19:21.234251Z","steps":["trace[465011293] 'range keys from in-memory index tree' (duration: 392.168814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:21.234329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:20.841814Z","time spent":"392.505079ms","remote":"127.0.0.1:53712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T05:19:21.434679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698876869918593069,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T05:19:21.452827Z","caller":"traceutil/trace.go:171","msg":"trace[174941915] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"518.83521ms","start":"2026-07-21T05:19:20.933965Z","end":"2026-07-21T05:19:21.4528Z","steps":["trace[174941915] 'read index received' (duration: 517.949326ms)","trace[174941915] 'applied index is now lower than readState.Index' (duration: 884.854µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:19:21.452877Z","caller":"traceutil/trace.go:171","msg":"trace[2040380748] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"520.658176ms","start":"2026-07-21T05:19:20.932196Z","end":"2026-07-21T05:19:21.452854Z","steps":["trace[2040380748] 'process raft request' (duration: 519.997117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:21.453086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.105043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lm2wz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-21T05:19:21.453128Z","caller":"traceutil/trace.go:171","msg":"trace[200447985] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lm2wz; range_end:; response_count:1; response_revision:588; }","duration":"519.244555ms","start":"2026-07-21T05:19:20.933873Z","end":"2026-07-21T05:19:21.453118Z","steps":["trace[200447985] 'agreement among raft nodes before linearized reading' (duration: 519.068413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:21.453165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:20.933848Z","time spent":"519.308476ms","remote":"127.0.0.1:53862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-lm2wz\" "} {"level":"warn","ts":"2026-07-21T05:19:21.453123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:20.932162Z","time spent":"520.767388ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:19:21.453488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.450914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T05:19:21.453527Z","caller":"traceutil/trace.go:171","msg":"trace[802729228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"457.556025ms","start":"2026-07-21T05:19:20.995962Z","end":"2026-07-21T05:19:21.453518Z","steps":["trace[802729228] 'agreement among raft nodes before linearized reading' (duration: 457.460804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:21.453555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:20.995931Z","time spent":"457.617807ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T05:19:21.453536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.133849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:19:21.4537Z","caller":"traceutil/trace.go:171","msg":"trace[364474299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"218.319223ms","start":"2026-07-21T05:19:21.235368Z","end":"2026-07-21T05:19:21.453687Z","steps":["trace[364474299] 'agreement among raft nodes before linearized reading' (duration: 218.13235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:21.453846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.994289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T05:19:21.453872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.464994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-21T05:19:21.45391Z","caller":"traceutil/trace.go:171","msg":"trace[1100989449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"271.503585ms","start":"2026-07-21T05:19:21.182397Z","end":"2026-07-21T05:19:21.453901Z","steps":["trace[1100989449] 'agreement among raft nodes before linearized reading' (duration: 271.404764ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:19:21.453917Z","caller":"traceutil/trace.go:171","msg":"trace[592903310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"447.123881ms","start":"2026-07-21T05:19:21.006773Z","end":"2026-07-21T05:19:21.453897Z","steps":["trace[592903310] 'agreement among raft nodes before linearized reading' (duration: 446.912898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:21.453955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:21.00675Z","time spent":"447.198132ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T05:19:42.702347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.28691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-21T05:19:42.70245Z","caller":"traceutil/trace.go:171","msg":"trace[552085938] range","detail":"{range_begin:/registry/masterleases/199.19.213.95; range_end:; response_count:1; response_revision:772; }","duration":"443.452822ms","start":"2026-07-21T05:19:42.258976Z","end":"2026-07-21T05:19:42.702429Z","steps":["trace[552085938] 'range keys from in-memory index tree' (duration: 443.078876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:19:42.70249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:19:42.258945Z","time spent":"443.536603ms","remote":"127.0.0.1:53732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.95\" "} {"level":"info","ts":"2026-07-21T05:20:15.911297Z","caller":"traceutil/trace.go:171","msg":"trace[224334416] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"159.194755ms","start":"2026-07-21T05:20:15.752065Z","end":"2026-07-21T05:20:15.911259Z","steps":["trace[224334416] 'process raft request' (duration: 137.045956ms)","trace[224334416] 'compare' (duration: 22.051288ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:20:16.004277Z","caller":"traceutil/trace.go:171","msg":"trace[1513194010] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"250.644944ms","start":"2026-07-21T05:20:15.753616Z","end":"2026-07-21T05:20:16.004261Z","steps":["trace[1513194010] 'process raft request' (duration: 250.521833ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:20:16.004394Z","caller":"traceutil/trace.go:171","msg":"trace[333500043] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1234; }","duration":"213.163444ms","start":"2026-07-21T05:20:15.791213Z","end":"2026-07-21T05:20:16.004376Z","steps":["trace[333500043] 'read index received' (duration: 97.889592ms)","trace[333500043] 'applied index is now lower than readState.Index' (duration: 115.272162ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:20:16.004535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.340895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T05:20:16.004536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.308936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-21T05:20:16.004557Z","caller":"traceutil/trace.go:171","msg":"trace[615489518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"163.388606ms","start":"2026-07-21T05:20:15.841161Z","end":"2026-07-21T05:20:16.00455Z","steps":["trace[615489518] 'agreement among raft nodes before linearized reading' (duration: 163.355715ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:20:16.00456Z","caller":"traceutil/trace.go:171","msg":"trace[33248688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1203; }","duration":"213.361057ms","start":"2026-07-21T05:20:15.791192Z","end":"2026-07-21T05:20:16.004553Z","steps":["trace[33248688] 'agreement among raft nodes before linearized reading' (duration: 213.258585ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:20:16.004494Z","caller":"traceutil/trace.go:171","msg":"trace[1093492537] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"243.600082ms","start":"2026-07-21T05:20:15.760863Z","end":"2026-07-21T05:20:16.004463Z","steps":["trace[1093492537] 'process raft request' (duration: 243.361789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:20:16.004677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.839144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T05:20:16.00473Z","caller":"traceutil/trace.go:171","msg":"trace[1835906157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1203; }","duration":"204.923905ms","start":"2026-07-21T05:20:15.799793Z","end":"2026-07-21T05:20:16.004717Z","steps":["trace[1835906157] 'agreement among raft nodes before linearized reading' (duration: 204.719762ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:20:17.068928Z","caller":"traceutil/trace.go:171","msg":"trace[687565795] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1274; }","duration":"107.127574ms","start":"2026-07-21T05:20:16.961782Z","end":"2026-07-21T05:20:17.068909Z","steps":["trace[687565795] 'read index received' (duration: 68.221293ms)","trace[687565795] 'applied index is now lower than readState.Index' (duration: 38.905231ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:20:17.069153Z","caller":"traceutil/trace.go:171","msg":"trace[1951570752] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"107.480898ms","start":"2026-07-21T05:20:16.961664Z","end":"2026-07-21T05:20:17.069144Z","steps":["trace[1951570752] 'process raft request' (duration: 107.106203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:20:17.069363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.56839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/self-signed-ca\" ","response":"range_response_count:1 size:2197"} {"level":"info","ts":"2026-07-21T05:20:17.069398Z","caller":"traceutil/trace.go:171","msg":"trace[909381756] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/self-signed-ca; range_end:; response_count:1; response_revision:1239; }","duration":"107.643891ms","start":"2026-07-21T05:20:16.961745Z","end":"2026-07-21T05:20:17.069389Z","steps":["trace[909381756] 'agreement among raft nodes before linearized reading' (duration: 107.57688ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:20:28.174031Z","caller":"traceutil/trace.go:171","msg":"trace[1083828580] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"112.055926ms","start":"2026-07-21T05:20:28.061956Z","end":"2026-07-21T05:20:28.174011Z","steps":["trace[1083828580] 'process raft request' (duration: 111.740831ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:20:34.514658Z","caller":"traceutil/trace.go:171","msg":"trace[8345668] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"150.826126ms","start":"2026-07-21T05:20:34.363811Z","end":"2026-07-21T05:20:34.514637Z","steps":["trace[8345668] 'process raft request' (duration: 150.682764ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:21:00.087571Z","caller":"traceutil/trace.go:171","msg":"trace[267765653] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"263.016902ms","start":"2026-07-21T05:20:59.824535Z","end":"2026-07-21T05:21:00.087552Z","steps":["trace[267765653] 'read index received' (duration: 262.83908ms)","trace[267765653] 'applied index is now lower than readState.Index' (duration: 176.842µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:21:00.087702Z","caller":"traceutil/trace.go:171","msg":"trace[490163811] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"295.38359ms","start":"2026-07-21T05:20:59.792307Z","end":"2026-07-21T05:21:00.08769Z","steps":["trace[490163811] 'process raft request' (duration: 295.121706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:21:00.087835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.277765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:21:00.087915Z","caller":"traceutil/trace.go:171","msg":"trace[2092932045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1846; }","duration":"246.403857ms","start":"2026-07-21T05:20:59.841494Z","end":"2026-07-21T05:21:00.087897Z","steps":["trace[2092932045] 'agreement among raft nodes before linearized reading' (duration: 246.289445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:21:00.08807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.526659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3932b830-498d-49fc-8cd7-e6ab11d6ef7a\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-21T05:21:00.08811Z","caller":"traceutil/trace.go:171","msg":"trace[1416631229] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3932b830-498d-49fc-8cd7-e6ab11d6ef7a; range_end:; response_count:1; response_revision:1846; }","duration":"263.598791ms","start":"2026-07-21T05:20:59.8245Z","end":"2026-07-21T05:21:00.088099Z","steps":["trace[1416631229] 'agreement among raft nodes before linearized reading' (duration: 263.471639ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:21:07.658572Z","caller":"traceutil/trace.go:171","msg":"trace[2029911315] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"119.579378ms","start":"2026-07-21T05:21:07.538969Z","end":"2026-07-21T05:21:07.658548Z","steps":["trace[2029911315] 'process raft request' (duration: 119.407635ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:21:14.342778Z","caller":"traceutil/trace.go:171","msg":"trace[874270460] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2029; }","duration":"126.489868ms","start":"2026-07-21T05:21:14.216263Z","end":"2026-07-21T05:21:14.342753Z","steps":["trace[874270460] 'read index received' (duration: 126.32028ms)","trace[874270460] 'applied index is now lower than readState.Index' (duration: 168.338µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:21:14.342975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.662445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:21:14.343048Z","caller":"traceutil/trace.go:171","msg":"trace[1958200855] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1958; }","duration":"126.833193ms","start":"2026-07-21T05:21:14.216204Z","end":"2026-07-21T05:21:14.343037Z","steps":["trace[1958200855] 'agreement among raft nodes before linearized reading' (duration: 126.669705ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:21:14.343268Z","caller":"traceutil/trace.go:171","msg":"trace[585492869] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"160.711581ms","start":"2026-07-21T05:21:14.182089Z","end":"2026-07-21T05:21:14.342801Z","steps":["trace[585492869] 'process raft request' (duration: 160.47573ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:21:17.076982Z","caller":"traceutil/trace.go:171","msg":"trace[463028110] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"140.866475ms","start":"2026-07-21T05:21:16.936075Z","end":"2026-07-21T05:21:17.076942Z","steps":["trace[463028110] 'process raft request' (duration: 140.639195ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:21:30.749117Z","caller":"traceutil/trace.go:171","msg":"trace[1743963384] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"184.256787ms","start":"2026-07-21T05:21:30.564831Z","end":"2026-07-21T05:21:30.749088Z","steps":["trace[1743963384] 'process raft request' (duration: 120.486515ms)","trace[1743963384] 'compare' (duration: 63.638916ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:21:31.789045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.09927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:21:31.789174Z","caller":"traceutil/trace.go:171","msg":"trace[1366458034] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"159.278982ms","start":"2026-07-21T05:21:31.629874Z","end":"2026-07-21T05:21:31.789153Z","steps":["trace[1366458034] 'process raft request' (duration: 55.819067ms)","trace[1366458034] 'compare' (duration: 102.912091ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:21:31.801042Z","caller":"traceutil/trace.go:171","msg":"trace[2069579900] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"126.699645ms","start":"2026-07-21T05:21:31.674324Z","end":"2026-07-21T05:21:31.801023Z","steps":["trace[2069579900] 'process raft request' (duration: 126.466314ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:21:40.767511Z","caller":"traceutil/trace.go:171","msg":"trace[1480067664] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"140.715957ms","start":"2026-07-21T05:21:40.626778Z","end":"2026-07-21T05:21:40.767494Z","steps":["trace[1480067664] 'read index received' (duration: 140.579862ms)","trace[1480067664] 'applied index is now lower than readState.Index' (duration: 135.415µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:21:40.767869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.069883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T05:21:40.767937Z","caller":"traceutil/trace.go:171","msg":"trace[436411005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2114; }","duration":"141.200519ms","start":"2026-07-21T05:21:40.626722Z","end":"2026-07-21T05:21:40.767923Z","steps":["trace[436411005] 'agreement among raft nodes before linearized reading' (duration: 140.911126ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:21:40.768139Z","caller":"traceutil/trace.go:171","msg":"trace[1764377586] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"174.516648ms","start":"2026-07-21T05:21:40.593514Z","end":"2026-07-21T05:21:40.768031Z","steps":["trace[1764377586] 'process raft request' (duration: 173.868811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:21:40.931565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.502085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25788"} {"level":"info","ts":"2026-07-21T05:21:40.931631Z","caller":"traceutil/trace.go:171","msg":"trace[724485537] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2114; }","duration":"159.611829ms","start":"2026-07-21T05:21:40.772005Z","end":"2026-07-21T05:21:40.931616Z","steps":["trace[724485537] 'range keys from in-memory index tree' (duration: 159.226523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:21:40.93268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.875732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:21:40.932937Z","caller":"traceutil/trace.go:171","msg":"trace[470107062] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"159.810378ms","start":"2026-07-21T05:21:40.773101Z","end":"2026-07-21T05:21:40.932911Z","steps":["trace[470107062] 'process raft request' (duration: 12.11927ms)","trace[470107062] 'compare' (duration: 146.702745ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:21:40.933098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.930636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25788"} {"level":"info","ts":"2026-07-21T05:21:40.933232Z","caller":"traceutil/trace.go:171","msg":"trace[1437750583] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2114; }","duration":"161.137305ms","start":"2026-07-21T05:21:40.772055Z","end":"2026-07-21T05:21:40.933192Z","steps":["trace[1437750583] 'range keys from in-memory index tree' (duration: 159.358418ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:21:40.933598Z","caller":"traceutil/trace.go:171","msg":"trace[723211646] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"112.856163ms","start":"2026-07-21T05:21:40.820002Z","end":"2026-07-21T05:21:40.932859Z","steps":["trace[723211646] 'read index received' (duration: 42.822µs)","trace[723211646] 'applied index is now lower than readState.Index' (duration: 112.811781ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:21:40.933801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.798573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-21T05:21:40.934725Z","caller":"traceutil/trace.go:171","msg":"trace[1998187432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2115; }","duration":"114.767245ms","start":"2026-07-21T05:21:40.819946Z","end":"2026-07-21T05:21:40.934713Z","steps":["trace[1998187432] 'agreement among raft nodes before linearized reading' (duration: 113.698319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:21:41.331676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.028669ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:21:41.331822Z","caller":"traceutil/trace.go:171","msg":"trace[1184496441] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2117; }","duration":"184.185006ms","start":"2026-07-21T05:21:41.147619Z","end":"2026-07-21T05:21:41.331804Z","steps":["trace[1184496441] 'range keys from in-memory index tree' (duration: 184.012848ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:28.270538Z","caller":"traceutil/trace.go:171","msg":"trace[666468975] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"388.14134ms","start":"2026-07-21T05:22:27.882343Z","end":"2026-07-21T05:22:28.270485Z","steps":["trace[666468975] 'process raft request' (duration: 387.942572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:28.27069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:27.88233Z","time spent":"388.285566ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:22:28.276135Z","caller":"traceutil/trace.go:171","msg":"trace[1572447922] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"391.590443ms","start":"2026-07-21T05:22:27.884527Z","end":"2026-07-21T05:22:28.276117Z","steps":["trace[1572447922] 'process raft request' (duration: 391.447937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:28.276252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:27.884518Z","time spent":"391.673977ms","remote":"127.0.0.1:53862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8232,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:22:28.276421Z","caller":"traceutil/trace.go:171","msg":"trace[830471370] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"292.872005ms","start":"2026-07-21T05:22:27.983536Z","end":"2026-07-21T05:22:28.276408Z","steps":["trace[830471370] 'process raft request' (duration: 292.543782ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:28.276611Z","caller":"traceutil/trace.go:171","msg":"trace[1648439886] linearizableReadLoop","detail":"{readStateIndex:2525; appliedIndex:2522; }","duration":"236.169501ms","start":"2026-07-21T05:22:28.040405Z","end":"2026-07-21T05:22:28.276574Z","steps":["trace[1648439886] 'read index received' (duration: 229.963172ms)","trace[1648439886] 'applied index is now lower than readState.Index' (duration: 6.205168ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:22:28.276805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.374199ms","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-07-21T05:22:28.276761Z","caller":"traceutil/trace.go:171","msg":"trace[97893391] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"204.522903ms","start":"2026-07-21T05:22:28.072143Z","end":"2026-07-21T05:22:28.276666Z","steps":["trace[97893391] 'process raft request' (duration: 204.240382ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:28.276838Z","caller":"traceutil/trace.go:171","msg":"trace[752338377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2437; }","duration":"236.460152ms","start":"2026-07-21T05:22:28.040368Z","end":"2026-07-21T05:22:28.276829Z","steps":["trace[752338377] 'agreement among raft nodes before linearized reading' (duration: 236.310927ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:30.611041Z","caller":"traceutil/trace.go:171","msg":"trace[884310789] linearizableReadLoop","detail":"{readStateIndex:2577; appliedIndex:2576; }","duration":"328.77029ms","start":"2026-07-21T05:22:30.282246Z","end":"2026-07-21T05:22:30.611017Z","steps":["trace[884310789] 'read index received' (duration: 328.717638ms)","trace[884310789] 'applied index is now lower than readState.Index' (duration: 51.602µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:22:30.611174Z","caller":"traceutil/trace.go:171","msg":"trace[739147926] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"332.570306ms","start":"2026-07-21T05:22:30.278572Z","end":"2026-07-21T05:22:30.611142Z","steps":["trace[739147926] 'process raft request' (duration: 332.314847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:30.611321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.051241ms","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-07-21T05:22:30.611392Z","caller":"traceutil/trace.go:171","msg":"trace[2005518095] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2488; }","duration":"329.122594ms","start":"2026-07-21T05:22:30.282234Z","end":"2026-07-21T05:22:30.611357Z","steps":["trace[2005518095] 'agreement among raft nodes before linearized reading' (duration: 328.977288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:30.611438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:30.282229Z","time spent":"329.196527ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":59,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-21T05:22:30.61154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:30.278553Z","time spent":"332.697871ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:22:30.612199Z","caller":"traceutil/trace.go:171","msg":"trace[539666822] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"329.587181ms","start":"2026-07-21T05:22:30.282597Z","end":"2026-07-21T05:22:30.612185Z","steps":["trace[539666822] 'process raft request' (duration: 329.340552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:30.612326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.783961ms","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-07-21T05:22:30.612424Z","caller":"traceutil/trace.go:171","msg":"trace[1627341440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2490; }","duration":"316.881945ms","start":"2026-07-21T05:22:30.295506Z","end":"2026-07-21T05:22:30.612388Z","steps":["trace[1627341440] 'agreement among raft nodes before linearized reading' (duration: 316.652566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:30.612336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.95473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T05:22:30.612463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:30.295496Z","time spent":"316.956187ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T05:22:30.612509Z","caller":"traceutil/trace.go:171","msg":"trace[268332001] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2490; }","duration":"244.180418ms","start":"2026-07-21T05:22:30.368313Z","end":"2026-07-21T05:22:30.612493Z","steps":["trace[268332001] 'agreement among raft nodes before linearized reading' (duration: 243.953939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:30.612609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:30.282572Z","time spent":"329.666014ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:22:30.612709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.167659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:22:30.612371Z","caller":"traceutil/trace.go:171","msg":"trace[167432926] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"329.747517ms","start":"2026-07-21T05:22:30.2826Z","end":"2026-07-21T05:22:30.612347Z","steps":["trace[167432926] 'process raft request' (duration: 329.247118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:30.613141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:30.282591Z","time spent":"330.481876ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:22:30.612777Z","caller":"traceutil/trace.go:171","msg":"trace[2099668015] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2490; }","duration":"303.288464ms","start":"2026-07-21T05:22:30.309472Z","end":"2026-07-21T05:22:30.612761Z","steps":["trace[2099668015] 'agreement among raft nodes before linearized reading' (duration: 303.151389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:30.613336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:30.309462Z","time spent":"303.823164ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-21T05:22:31.355906Z","caller":"traceutil/trace.go:171","msg":"trace[452936956] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"112.082366ms","start":"2026-07-21T05:22:31.243806Z","end":"2026-07-21T05:22:31.355888Z","steps":["trace[452936956] 'process raft request' (duration: 111.980652ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:31.357724Z","caller":"traceutil/trace.go:171","msg":"trace[1945218404] linearizableReadLoop","detail":"{readStateIndex:2584; appliedIndex:2583; }","duration":"107.508701ms","start":"2026-07-21T05:22:31.250193Z","end":"2026-07-21T05:22:31.357701Z","steps":["trace[1945218404] 'read index received' (duration: 106.067536ms)","trace[1945218404] 'applied index is now lower than readState.Index' (duration: 1.440305ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:22:31.357907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.693968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T05:22:31.357937Z","caller":"traceutil/trace.go:171","msg":"trace[1301350260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2495; }","duration":"107.750941ms","start":"2026-07-21T05:22:31.250175Z","end":"2026-07-21T05:22:31.357926Z","steps":["trace[1301350260] 'agreement among raft nodes before linearized reading' (duration: 107.603645ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:31.358198Z","caller":"traceutil/trace.go:171","msg":"trace[1993764306] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"114.233859ms","start":"2026-07-21T05:22:31.243957Z","end":"2026-07-21T05:22:31.358191Z","steps":["trace[1993764306] 'process raft request' (duration: 113.647577ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:37.508837Z","caller":"traceutil/trace.go:171","msg":"trace[1963251065] linearizableReadLoop","detail":"{readStateIndex:2619; appliedIndex:2619; }","duration":"120.324643ms","start":"2026-07-21T05:22:37.388485Z","end":"2026-07-21T05:22:37.508809Z","steps":["trace[1963251065] 'read index received' (duration: 120.297472ms)","trace[1963251065] 'applied index is now lower than readState.Index' (duration: 7.61µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:22:37.508997Z","caller":"traceutil/trace.go:171","msg":"trace[1046635852] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"128.486361ms","start":"2026-07-21T05:22:37.380488Z","end":"2026-07-21T05:22:37.508974Z","steps":["trace[1046635852] 'process raft request' (duration: 128.152808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:37.50907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.574012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T05:22:37.509109Z","caller":"traceutil/trace.go:171","msg":"trace[1570907320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2529; }","duration":"120.654205ms","start":"2026-07-21T05:22:37.38844Z","end":"2026-07-21T05:22:37.509094Z","steps":["trace[1570907320] 'agreement among raft nodes before linearized reading' (duration: 120.493088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:03.252152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.940554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-21T05:23:03.252231Z","caller":"traceutil/trace.go:171","msg":"trace[903848672] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2669; }","duration":"222.08079ms","start":"2026-07-21T05:23:03.030126Z","end":"2026-07-21T05:23:03.252206Z","steps":["trace[903848672] 'range keys from in-memory index tree' (duration: 221.748178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:03.522254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.780164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:23:03.522381Z","caller":"traceutil/trace.go:171","msg":"trace[398299109] linearizableReadLoop","detail":"{readStateIndex:2768; appliedIndex:2767; }","duration":"141.846062ms","start":"2026-07-21T05:23:03.380486Z","end":"2026-07-21T05:23:03.522332Z","steps":["trace[398299109] 'read index received' (duration: 26.83713ms)","trace[398299109] 'applied index is now lower than readState.Index' (duration: 115.007812ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:23:03.522448Z","caller":"traceutil/trace.go:171","msg":"trace[513300186] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"209.870034ms","start":"2026-07-21T05:23:03.312557Z","end":"2026-07-21T05:23:03.522427Z","steps":["trace[513300186] 'process raft request' (duration: 94.849601ms)","trace[513300186] 'compare' (duration: 114.66526ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:23:03.522491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.010128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:23:03.52253Z","caller":"traceutil/trace.go:171","msg":"trace[1824864879] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2671; }","duration":"142.062139ms","start":"2026-07-21T05:23:03.380444Z","end":"2026-07-21T05:23:03.522506Z","steps":["trace[1824864879] 'agreement among raft nodes before linearized reading' (duration: 142.016368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:03.522863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.566222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-21T05:23:03.522898Z","caller":"traceutil/trace.go:171","msg":"trace[1037958542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2671; }","duration":"108.666895ms","start":"2026-07-21T05:23:03.414222Z","end":"2026-07-21T05:23:03.522888Z","steps":["trace[1037958542] 'agreement among raft nodes before linearized reading' (duration: 108.51995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:09.060502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.905208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T05:23:09.060564Z","caller":"traceutil/trace.go:171","msg":"trace[1006664404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2698; }","duration":"166.001231ms","start":"2026-07-21T05:23:08.894546Z","end":"2026-07-21T05:23:09.060547Z","steps":["trace[1006664404] 'range keys from in-memory index tree' (duration: 165.725801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:09.060808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.913021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-21T05:23:09.060834Z","caller":"traceutil/trace.go:171","msg":"trace[1830921161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2698; }","duration":"146.000084ms","start":"2026-07-21T05:23:08.914827Z","end":"2026-07-21T05:23:09.060827Z","steps":["trace[1830921161] 'range keys from in-memory index tree' (duration: 145.777026ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:09.061106Z","caller":"traceutil/trace.go:171","msg":"trace[446054338] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"113.830415ms","start":"2026-07-21T05:23:08.947266Z","end":"2026-07-21T05:23:09.061096Z","steps":["trace[446054338] 'process raft request' (duration: 25.320537ms)","trace[446054338] 'compare' (duration: 88.441386ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:23:09.242084Z","caller":"traceutil/trace.go:171","msg":"trace[1390320067] linearizableReadLoop","detail":"{readStateIndex:2799; appliedIndex:2797; }","duration":"108.526114ms","start":"2026-07-21T05:23:09.133539Z","end":"2026-07-21T05:23:09.242065Z","steps":["trace[1390320067] 'read index received' (duration: 108.269105ms)","trace[1390320067] 'applied index is now lower than readState.Index' (duration: 256.119µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:23:09.242175Z","caller":"traceutil/trace.go:171","msg":"trace[1918497446] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"176.898839ms","start":"2026-07-21T05:23:09.065257Z","end":"2026-07-21T05:23:09.242156Z","steps":["trace[1918497446] 'process raft request' (duration: 176.612999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:09.242219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.665618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c4369494e8adb7\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-21T05:23:09.242242Z","caller":"traceutil/trace.go:171","msg":"trace[215838703] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c4369494e8adb7; range_end:; response_count:1; response_revision:2701; }","duration":"108.754022ms","start":"2026-07-21T05:23:09.133482Z","end":"2026-07-21T05:23:09.242236Z","steps":["trace[215838703] 'agreement among raft nodes before linearized reading' (duration: 108.648928ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:09.242419Z","caller":"traceutil/trace.go:171","msg":"trace[1764011603] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"176.917989ms","start":"2026-07-21T05:23:09.065493Z","end":"2026-07-21T05:23:09.242411Z","steps":["trace[1764011603] 'process raft request' (duration: 176.505814ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:45.08931Z","caller":"traceutil/trace.go:171","msg":"trace[165294793] transaction","detail":"{read_only:false; response_revision:3201; number_of_response:1; }","duration":"117.908064ms","start":"2026-07-21T05:24:44.971366Z","end":"2026-07-21T05:24:45.089274Z","steps":["trace[165294793] 'process raft request' (duration: 117.79275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:24:45.260666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.287255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:24:45.261758Z","caller":"traceutil/trace.go:171","msg":"trace[310652178] transaction","detail":"{read_only:false; response_revision:3202; number_of_response:1; }","duration":"154.688711ms","start":"2026-07-21T05:24:45.107045Z","end":"2026-07-21T05:24:45.261734Z","steps":["trace[310652178] 'process raft request' (duration: 38.240092ms)","trace[310652178] 'compare' (duration: 115.140091ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:24:45.275296Z","caller":"traceutil/trace.go:171","msg":"trace[595313571] transaction","detail":"{read_only:false; response_revision:3203; number_of_response:1; }","duration":"168.139919ms","start":"2026-07-21T05:24:45.107142Z","end":"2026-07-21T05:24:45.275282Z","steps":["trace[595313571] 'process raft request' (duration: 167.979595ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:45.275353Z","caller":"traceutil/trace.go:171","msg":"trace[1010164194] transaction","detail":"{read_only:false; response_revision:3206; number_of_response:1; }","duration":"166.704677ms","start":"2026-07-21T05:24:45.108629Z","end":"2026-07-21T05:24:45.275334Z","steps":["trace[1010164194] 'process raft request' (duration: 166.648155ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:45.275386Z","caller":"traceutil/trace.go:171","msg":"trace[736348179] transaction","detail":"{read_only:false; response_revision:3205; number_of_response:1; }","duration":"167.025137ms","start":"2026-07-21T05:24:45.108332Z","end":"2026-07-21T05:24:45.275357Z","steps":["trace[736348179] 'process raft request' (duration: 166.922604ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:45.275582Z","caller":"traceutil/trace.go:171","msg":"trace[1063502281] transaction","detail":"{read_only:false; response_revision:3204; number_of_response:1; }","duration":"168.296804ms","start":"2026-07-21T05:24:45.107277Z","end":"2026-07-21T05:24:45.275574Z","steps":["trace[1063502281] 'process raft request' (duration: 167.942004ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:45.275712Z","caller":"traceutil/trace.go:171","msg":"trace[746286473] transaction","detail":"{read_only:false; response_revision:3207; number_of_response:1; }","duration":"167.010807ms","start":"2026-07-21T05:24:45.108681Z","end":"2026-07-21T05:24:45.275692Z","steps":["trace[746286473] 'process raft request' (duration: 166.628115ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:45.275871Z","caller":"traceutil/trace.go:171","msg":"trace[2082621333] transaction","detail":"{read_only:false; response_revision:3208; number_of_response:1; }","duration":"164.188752ms","start":"2026-07-21T05:24:45.111661Z","end":"2026-07-21T05:24:45.27585Z","steps":["trace[2082621333] 'process raft request' (duration: 163.860353ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:45.275903Z","caller":"traceutil/trace.go:171","msg":"trace[877591140] transaction","detail":"{read_only:false; response_revision:3209; number_of_response:1; }","duration":"134.291398ms","start":"2026-07-21T05:24:45.1416Z","end":"2026-07-21T05:24:45.275891Z","steps":["trace[877591140] 'process raft request' (duration: 134.187474ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:26:56.633528Z","caller":"traceutil/trace.go:171","msg":"trace[2322256] linearizableReadLoop","detail":"{readStateIndex:4785; appliedIndex:4784; }","duration":"127.383743ms","start":"2026-07-21T05:26:56.506082Z","end":"2026-07-21T05:26:56.633466Z","steps":["trace[2322256] 'read index received' (duration: 127.090076ms)","trace[2322256] 'applied index is now lower than readState.Index' (duration: 291.837µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:26:56.633708Z","caller":"traceutil/trace.go:171","msg":"trace[688309647] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"142.427664ms","start":"2026-07-21T05:26:56.491266Z","end":"2026-07-21T05:26:56.633694Z","steps":["trace[688309647] 'process raft request' (duration: 141.961012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:26:56.633821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.721271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-21T05:26:56.63388Z","caller":"traceutil/trace.go:171","msg":"trace[1788850439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4611; }","duration":"127.813844ms","start":"2026-07-21T05:26:56.506053Z","end":"2026-07-21T05:26:56.633867Z","steps":["trace[1788850439] 'agreement among raft nodes before linearized reading' (duration: 127.64032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:26:56.646725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.166528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-21T05:26:56.646807Z","caller":"traceutil/trace.go:171","msg":"trace[2132059772] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4612; }","duration":"127.279201ms","start":"2026-07-21T05:26:56.519507Z","end":"2026-07-21T05:26:56.646786Z","steps":["trace[2132059772] 'agreement among raft nodes before linearized reading' (duration: 127.057915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:02.001786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.500689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-21T05:27:02.001867Z","caller":"traceutil/trace.go:171","msg":"trace[274722177] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4706; }","duration":"152.594462ms","start":"2026-07-21T05:27:01.849256Z","end":"2026-07-21T05:27:02.00185Z","steps":["trace[274722177] 'range keys from in-memory index tree' (duration: 152.320745ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:02.285706Z","caller":"traceutil/trace.go:171","msg":"trace[1003761639] linearizableReadLoop","detail":"{readStateIndex:4882; appliedIndex:4881; }","duration":"218.475037ms","start":"2026-07-21T05:27:02.067211Z","end":"2026-07-21T05:27:02.285687Z","steps":["trace[1003761639] 'read index received' (duration: 218.394515ms)","trace[1003761639] 'applied index is now lower than readState.Index' (duration: 79.252µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:27:02.285758Z","caller":"traceutil/trace.go:171","msg":"trace[2104142127] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"242.421141ms","start":"2026-07-21T05:27:02.043311Z","end":"2026-07-21T05:27:02.285732Z","steps":["trace[2104142127] 'process raft request' (duration: 242.241567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:02.285841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.60857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:27:02.285884Z","caller":"traceutil/trace.go:171","msg":"trace[1642047660] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4707; }","duration":"218.689222ms","start":"2026-07-21T05:27:02.067185Z","end":"2026-07-21T05:27:02.285874Z","steps":["trace[1642047660] 'agreement among raft nodes before linearized reading' (duration: 218.60727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:02.371375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.636866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-07-21T05:27:02.371451Z","caller":"traceutil/trace.go:171","msg":"trace[1192082008] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4708; }","duration":"129.761859ms","start":"2026-07-21T05:27:02.24167Z","end":"2026-07-21T05:27:02.371431Z","steps":["trace[1192082008] 'agreement among raft nodes before linearized reading' (duration: 129.519103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:02.371386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.772335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-54dcbd4d7b-7rvj9.18c436d071a9c934\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-21T05:27:02.371477Z","caller":"traceutil/trace.go:171","msg":"trace[857369906] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"242.022232ms","start":"2026-07-21T05:27:02.129423Z","end":"2026-07-21T05:27:02.371446Z","steps":["trace[857369906] 'process raft request' (duration: 228.820439ms)","trace[857369906] 'compare' (duration: 12.802073ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:27:02.371869Z","caller":"traceutil/trace.go:171","msg":"trace[1672642392] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-54dcbd4d7b-7rvj9.18c436d071a9c934; range_end:; response_count:1; response_revision:4708; }","duration":"129.125034ms","start":"2026-07-21T05:27:02.242562Z","end":"2026-07-21T05:27:02.371687Z","steps":["trace[1672642392] 'agreement among raft nodes before linearized reading' (duration: 128.635662ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:02.761457Z","caller":"traceutil/trace.go:171","msg":"trace[75386451] linearizableReadLoop","detail":"{readStateIndex:4892; appliedIndex:4891; }","duration":"106.501831ms","start":"2026-07-21T05:27:02.654919Z","end":"2026-07-21T05:27:02.761421Z","steps":["trace[75386451] 'read index received' (duration: 106.136702ms)","trace[75386451] 'applied index is now lower than readState.Index' (duration: 363.829µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:27:02.761538Z","caller":"traceutil/trace.go:171","msg":"trace[277215414] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"141.125737ms","start":"2026-07-21T05:27:02.620368Z","end":"2026-07-21T05:27:02.761494Z","steps":["trace[277215414] 'process raft request' (duration: 140.353878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:02.761795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.86232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-21T05:27:02.761849Z","caller":"traceutil/trace.go:171","msg":"trace[2062855535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4716; }","duration":"106.955022ms","start":"2026-07-21T05:27:02.65488Z","end":"2026-07-21T05:27:02.761835Z","steps":["trace[2062855535] 'agreement among raft nodes before linearized reading' (duration: 106.681995ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:02.810537Z","caller":"traceutil/trace.go:171","msg":"trace[1690354775] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"140.81102ms","start":"2026-07-21T05:27:02.669688Z","end":"2026-07-21T05:27:02.810499Z","steps":["trace[1690354775] 'process raft request' (duration: 140.585114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:02.810605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.045195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-21T05:27:02.810677Z","caller":"traceutil/trace.go:171","msg":"trace[1713858546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4717; }","duration":"132.155008ms","start":"2026-07-21T05:27:02.678503Z","end":"2026-07-21T05:27:02.810658Z","steps":["trace[1713858546] 'agreement among raft nodes before linearized reading' (duration: 131.921672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:02.810694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.661865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-21T05:27:02.810775Z","caller":"traceutil/trace.go:171","msg":"trace[738836076] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4717; }","duration":"115.779088ms","start":"2026-07-21T05:27:02.694974Z","end":"2026-07-21T05:27:02.810753Z","steps":["trace[738836076] 'agreement among raft nodes before linearized reading' (duration: 115.530452ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:12.526182Z","caller":"traceutil/trace.go:171","msg":"trace[1552269585] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"156.498519ms","start":"2026-07-21T05:27:12.36966Z","end":"2026-07-21T05:27:12.526159Z","steps":["trace[1552269585] 'process raft request' (duration: 133.39689ms)","trace[1552269585] 'compare' (duration: 22.7433ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:27:12.526083Z","caller":"traceutil/trace.go:171","msg":"trace[1626252509] linearizableReadLoop","detail":"{readStateIndex:5040; appliedIndex:5039; }","duration":"111.774519ms","start":"2026-07-21T05:27:12.41427Z","end":"2026-07-21T05:27:12.526045Z","steps":["trace[1626252509] 'read index received' (duration: 88.797044ms)","trace[1626252509] 'applied index is now lower than readState.Index' (duration: 22.975885ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:27:12.526466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.178779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-21T05:27:12.526557Z","caller":"traceutil/trace.go:171","msg":"trace[45752598] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4860; }","duration":"112.318233ms","start":"2026-07-21T05:27:12.414216Z","end":"2026-07-21T05:27:12.526534Z","steps":["trace[45752598] 'agreement among raft nodes before linearized reading' (duration: 112.056216ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:16.707716Z","caller":"traceutil/trace.go:171","msg":"trace[2047567263] linearizableReadLoop","detail":"{readStateIndex:5072; appliedIndex:5071; }","duration":"386.075409ms","start":"2026-07-21T05:27:16.321612Z","end":"2026-07-21T05:27:16.707688Z","steps":["trace[2047567263] 'read index received' (duration: 385.907135ms)","trace[2047567263] 'applied index is now lower than readState.Index' (duration: 166.554µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:27:16.707779Z","caller":"traceutil/trace.go:171","msg":"trace[1275748752] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"386.49944ms","start":"2026-07-21T05:27:16.321232Z","end":"2026-07-21T05:27:16.707732Z","steps":["trace[1275748752] 'process raft request' (duration: 386.245204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:16.708211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.21219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-21T05:27:16.708286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:27:16.32122Z","time spent":"386.880339ms","remote":"127.0.0.1:53774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:27:16.708569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.530927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"warn","ts":"2026-07-21T05:27:16.70859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.310826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-07-21T05:27:16.708694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.253706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:27:16.708737Z","caller":"traceutil/trace.go:171","msg":"trace[837918470] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4892; }","duration":"176.318098ms","start":"2026-07-21T05:27:16.532409Z","end":"2026-07-21T05:27:16.708727Z","steps":["trace[837918470] 'agreement among raft nodes before linearized reading' (duration: 176.251756ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:16.708742Z","caller":"traceutil/trace.go:171","msg":"trace[959583354] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4892; }","duration":"312.491911ms","start":"2026-07-21T05:27:16.396225Z","end":"2026-07-21T05:27:16.708717Z","steps":["trace[959583354] 'agreement among raft nodes before linearized reading' (duration: 312.125142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:16.708947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:27:16.396203Z","time spent":"312.671956ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-21T05:27:16.708614Z","caller":"traceutil/trace.go:171","msg":"trace[783441222] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4892; }","duration":"343.595598ms","start":"2026-07-21T05:27:16.365006Z","end":"2026-07-21T05:27:16.708602Z","steps":["trace[783441222] 'agreement among raft nodes before linearized reading' (duration: 343.411604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:16.709154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:27:16.364989Z","time spent":"344.151962ms","remote":"127.0.0.1:53862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-21T05:27:16.708386Z","caller":"traceutil/trace.go:171","msg":"trace[584634235] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4892; }","duration":"373.416235ms","start":"2026-07-21T05:27:16.334942Z","end":"2026-07-21T05:27:16.708358Z","steps":["trace[584634235] 'agreement among raft nodes before linearized reading' (duration: 373.120508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:16.708295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.660444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"warn","ts":"2026-07-21T05:27:16.709528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:27:16.334937Z","time spent":"374.578953ms","remote":"127.0.0.1:53950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":37,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-07-21T05:27:16.709827Z","caller":"traceutil/trace.go:171","msg":"trace[1895899366] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4892; }","duration":"388.202661ms","start":"2026-07-21T05:27:16.321581Z","end":"2026-07-21T05:27:16.709784Z","steps":["trace[1895899366] 'agreement among raft nodes before linearized reading' (duration: 386.268644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:16.710047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:27:16.321568Z","time spent":"388.418915ms","remote":"127.0.0.1:53874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":1476,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-07-21T05:27:16.841304Z","caller":"traceutil/trace.go:171","msg":"trace[809655782] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"120.525707ms","start":"2026-07-21T05:27:16.72075Z","end":"2026-07-21T05:27:16.841276Z","steps":["trace[809655782] 'process raft request' (duration: 85.452044ms)","trace[809655782] 'compare' (duration: 34.881648ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:27:17.025592Z","caller":"traceutil/trace.go:171","msg":"trace[28194115] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"107.608486ms","start":"2026-07-21T05:27:16.917952Z","end":"2026-07-21T05:27:17.02556Z","steps":["trace[28194115] 'process raft request' (duration: 35.714568ms)","trace[28194115] 'compare' (duration: 71.756704ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:27:17.037256Z","caller":"traceutil/trace.go:171","msg":"trace[1382383721] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"116.660893ms","start":"2026-07-21T05:27:16.920578Z","end":"2026-07-21T05:27:17.037239Z","steps":["trace[1382383721] 'process raft request' (duration: 116.060089ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:17.037312Z","caller":"traceutil/trace.go:171","msg":"trace[1549396596] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"116.226763ms","start":"2026-07-21T05:27:16.921051Z","end":"2026-07-21T05:27:17.037278Z","steps":["trace[1549396596] 'process raft request' (duration: 115.66915ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:17.037378Z","caller":"traceutil/trace.go:171","msg":"trace[1610473049] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"113.085837ms","start":"2026-07-21T05:27:16.92427Z","end":"2026-07-21T05:27:17.037356Z","steps":["trace[1610473049] 'process raft request' (duration: 112.536824ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:17.037589Z","caller":"traceutil/trace.go:171","msg":"trace[1911727319] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"113.708752ms","start":"2026-07-21T05:27:16.923872Z","end":"2026-07-21T05:27:17.037581Z","steps":["trace[1911727319] 'process raft request' (duration: 112.897543ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:17.194016Z","caller":"traceutil/trace.go:171","msg":"trace[1253328952] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"149.647121ms","start":"2026-07-21T05:27:17.044343Z","end":"2026-07-21T05:27:17.19399Z","steps":["trace[1253328952] 'process raft request' (duration: 149.534559ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:17.19413Z","caller":"traceutil/trace.go:171","msg":"trace[513941196] linearizableReadLoop","detail":"{readStateIndex:5089; appliedIndex:5086; }","duration":"115.090472ms","start":"2026-07-21T05:27:17.079025Z","end":"2026-07-21T05:27:17.194116Z","steps":["trace[513941196] 'read index received' (duration: 74.364986ms)","trace[513941196] 'applied index is now lower than readState.Index' (duration: 40.724396ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:27:17.194565Z","caller":"traceutil/trace.go:171","msg":"trace[1791594108] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"146.384503ms","start":"2026-07-21T05:27:17.048138Z","end":"2026-07-21T05:27:17.194522Z","steps":["trace[1791594108] 'process raft request' (duration: 145.801219ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:17.194044Z","caller":"traceutil/trace.go:171","msg":"trace[1653549418] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"149.715843ms","start":"2026-07-21T05:27:17.044302Z","end":"2026-07-21T05:27:17.194017Z","steps":["trace[1653549418] 'process raft request' (duration: 109.13986ms)","trace[1653549418] 'compare' (duration: 40.265886ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:27:17.197317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.283339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-21T05:27:17.197358Z","caller":"traceutil/trace.go:171","msg":"trace[1947678866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4909; }","duration":"118.381251ms","start":"2026-07-21T05:27:17.078968Z","end":"2026-07-21T05:27:17.197349Z","steps":["trace[1947678866] 'agreement among raft nodes before linearized reading' (duration: 115.318267ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:28:39.57777Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-07-21T05:28:39.65521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"76.192984ms","hash":2424273253,"current-db-size-bytes":54464512,"current-db-size":"54 MB","current-db-size-in-use-bytes":29310976,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-21T05:28:39.65528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2424273253,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-07-21T05:30:28.968981Z","caller":"traceutil/trace.go:171","msg":"trace[1298264590] transaction","detail":"{read_only:false; response_revision:6211; number_of_response:1; }","duration":"118.29748ms","start":"2026-07-21T05:30:28.85065Z","end":"2026-07-21T05:30:28.968947Z","steps":["trace[1298264590] 'process raft request' (duration: 69.111968ms)","trace[1298264590] 'compare' (duration: 49.014909ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:03.214452Z","caller":"traceutil/trace.go:171","msg":"trace[1995011217] transaction","detail":"{read_only:false; response_revision:6543; number_of_response:1; }","duration":"181.473751ms","start":"2026-07-21T05:31:03.032941Z","end":"2026-07-21T05:31:03.214415Z","steps":["trace[1995011217] 'process raft request' (duration: 173.121205ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:39.590082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5559} {"level":"info","ts":"2026-07-21T05:33:39.685446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5559,"took":"94.240718ms","hash":4106848025,"current-db-size-bytes":54464512,"current-db-size":"54 MB","current-db-size-in-use-bytes":18341888,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-21T05:33:39.685589Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4106848025,"revision":5559,"compact-revision":2862}