{"level":"warn","ts":"2026-07-23T23:50:40.321646Z","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-23T23:50:40.321756Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-23T23:50:40.321828Z","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-23T23:50:40.321839Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-07-23T23:50:40.321859Z","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-23T23:50:40.322523Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-07-23T23:50:40.322595Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-23T23:50:40.325774Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.968674ms"} {"level":"info","ts":"2026-07-23T23:50:40.334795Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-07-23T23:50:40.334945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T23:50:40.334976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-07-23T23:50:40.33499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T23:50:40.335002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-07-23T23:50:40.335049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-07-23T23:50:40.34003Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T23:50:40.346117Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T23:50:40.347952Z","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-23T23:50:40.351466Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T23:50:40.351992Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T23:50:40.352146Z","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-23T23:50:40.352187Z","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-23T23:50:40.352207Z","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-23T23:50:40.353835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-07-23T23:50:40.353928Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-07-23T23:50:40.354641Z","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-23T23:50:40.354917Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T23:50:40.354947Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T23:50:40.355118Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-07-23T23:50:40.35514Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-07-23T23:50:41.135738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T23:50:41.135815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T23:50:41.135871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-07-23T23:50:41.135903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-07-23T23:50:41.135922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-07-23T23:50:41.135959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-07-23T23:50:41.135983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-07-23T23:50:41.137341Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:50:41.13841Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T23:50:41.13842Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:50:41.138584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:50:41.138701Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T23:50:41.138787Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T23:50:41.139608Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:50:41.139808Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:50:41.139864Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:50:41.142108Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-07-23T23:50:41.142529Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T23:51:19.195417Z","caller":"traceutil/trace.go:171","msg":"trace[467309982] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"173.891333ms","start":"2026-07-23T23:51:19.021497Z","end":"2026-07-23T23:51:19.195388Z","steps":["trace[467309982] 'process raft request' (duration: 173.710619ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:19.418212Z","caller":"traceutil/trace.go:171","msg":"trace[1420464502] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"395.949203ms","start":"2026-07-23T23:51:19.022229Z","end":"2026-07-23T23:51:19.418178Z","steps":["trace[1420464502] 'process raft request' (duration: 395.649377ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:19.418404Z","caller":"traceutil/trace.go:171","msg":"trace[1577789166] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"396.053496ms","start":"2026-07-23T23:51:19.022296Z","end":"2026-07-23T23:51:19.41835Z","steps":["trace[1577789166] 'process raft request' (duration: 395.7837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.418788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.022215Z","time spent":"396.083836ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:51:19.418812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.022291Z","time spent":"396.232359ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:19.420045Z","caller":"traceutil/trace.go:171","msg":"trace[1383274431] transaction","detail":"{read_only:false; number_of_response:0; response_revision:503; }","duration":"395.552404ms","start":"2026-07-23T23:51:19.024479Z","end":"2026-07-23T23:51:19.420031Z","steps":["trace[1383274431] 'process raft request' (duration: 395.266488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.420119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.024472Z","time spent":"395.601655ms","remote":"127.0.0.1:40092","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:51:19.420211Z","caller":"traceutil/trace.go:171","msg":"trace[1882384373] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"394.937992ms","start":"2026-07-23T23:51:19.025255Z","end":"2026-07-23T23:51:19.420192Z","steps":["trace[1882384373] 'process raft request' (duration: 394.736638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.420361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.025247Z","time spent":"395.012443ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:19.420447Z","caller":"traceutil/trace.go:171","msg":"trace[1219410588] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"392.624991ms","start":"2026-07-23T23:51:19.027811Z","end":"2026-07-23T23:51:19.420436Z","steps":["trace[1219410588] 'process raft request' (duration: 392.335735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.420494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.027805Z","time spent":"392.666223ms","remote":"127.0.0.1:39626","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-23T23:51:19.420628Z","caller":"traceutil/trace.go:171","msg":"trace[1119824976] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"386.602871ms","start":"2026-07-23T23:51:19.034014Z","end":"2026-07-23T23:51:19.420617Z","steps":["trace[1119824976] 'process raft request' (duration: 386.365116ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:19.420611Z","caller":"traceutil/trace.go:171","msg":"trace[1351425723] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"397.438836ms","start":"2026-07-23T23:51:19.022344Z","end":"2026-07-23T23:51:19.419782Z","steps":["trace[1351425723] 'process raft request' (duration: 396.956736ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:19.420754Z","caller":"traceutil/trace.go:171","msg":"trace[671177679] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:519; }","duration":"364.009754ms","start":"2026-07-23T23:51:19.05668Z","end":"2026-07-23T23:51:19.42069Z","steps":["trace[671177679] 'read index received' (duration: 138.572801ms)","trace[671177679] 'applied index is now lower than readState.Index' (duration: 225.435453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:51:19.420836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.022338Z","time spent":"398.383886ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:51:19.420666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.034005Z","time spent":"386.645083ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:51:19.420921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.215079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T23:51:19.420949Z","caller":"traceutil/trace.go:171","msg":"trace[1591970197] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"245.176249ms","start":"2026-07-23T23:51:19.175737Z","end":"2026-07-23T23:51:19.420913Z","steps":["trace[1591970197] 'process raft request' (duration: 244.869983ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:19.421017Z","caller":"traceutil/trace.go:171","msg":"trace[762008534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"364.365681ms","start":"2026-07-23T23:51:19.056635Z","end":"2026-07-23T23:51:19.421001Z","steps":["trace[762008534] 'agreement among raft nodes before linearized reading' (duration: 364.186777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.421063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.056617Z","time spent":"364.434983ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-23T23:51:19.421085Z","caller":"traceutil/trace.go:171","msg":"trace[1154448827] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"359.290891ms","start":"2026-07-23T23:51:19.061443Z","end":"2026-07-23T23:51:19.420734Z","steps":["trace[1154448827] 'process raft request' (duration: 359.075217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.421137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.061425Z","time spent":"359.68693ms","remote":"127.0.0.1:39626","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-23T23:51:19.653706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.593915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:51:19.653978Z","caller":"traceutil/trace.go:171","msg":"trace[692055460] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"451.244677ms","start":"2026-07-23T23:51:19.202704Z","end":"2026-07-23T23:51:19.653949Z","steps":["trace[692055460] 'process raft request' (duration: 284.197492ms)","trace[692055460] 'compare' (duration: 166.417031ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:51:19.654122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.202664Z","time spent":"451.38218ms","remote":"127.0.0.1:40092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:51:19.654208Z","caller":"traceutil/trace.go:171","msg":"trace[1955950319] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"446.944404ms","start":"2026-07-23T23:51:19.207185Z","end":"2026-07-23T23:51:19.65413Z","steps":["trace[1955950319] 'process raft request' (duration: 446.661717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.65437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.207173Z","time spent":"447.116618ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:19.748274Z","caller":"traceutil/trace.go:171","msg":"trace[2008146751] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"320.830863ms","start":"2026-07-23T23:51:19.427431Z","end":"2026-07-23T23:51:19.748262Z","steps":["trace[2008146751] 'process raft request' (duration: 320.673609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.748393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.268112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"warn","ts":"2026-07-23T23:51:19.748406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.427405Z","time spent":"320.936524ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:19.748422Z","caller":"traceutil/trace.go:171","msg":"trace[1342530472] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:512; }","duration":"327.370624ms","start":"2026-07-23T23:51:19.421043Z","end":"2026-07-23T23:51:19.748413Z","steps":["trace[1342530472] 'agreement among raft nodes before linearized reading' (duration: 327.279432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.748445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.421012Z","time spent":"327.427685ms","remote":"127.0.0.1:40092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1036,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-23T23:51:19.748458Z","caller":"traceutil/trace.go:171","msg":"trace[1577116979] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"315.559878ms","start":"2026-07-23T23:51:19.432864Z","end":"2026-07-23T23:51:19.748424Z","steps":["trace[1577116979] 'process raft request' (duration: 315.348563ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:19.74827Z","caller":"traceutil/trace.go:171","msg":"trace[1063856421] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:529; }","duration":"327.120648ms","start":"2026-07-23T23:51:19.421125Z","end":"2026-07-23T23:51:19.748246Z","steps":["trace[1063856421] 'read index received' (duration: 65.784959ms)","trace[1063856421] 'applied index is now lower than readState.Index' (duration: 261.333049ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:19.748542Z","caller":"traceutil/trace.go:171","msg":"trace[576274708] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"307.187777ms","start":"2026-07-23T23:51:19.441308Z","end":"2026-07-23T23:51:19.748496Z","steps":["trace[576274708] 'process raft request' (duration: 306.956983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.748625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.432847Z","time spent":"315.691551ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:19.748682Z","caller":"traceutil/trace.go:171","msg":"trace[1077866278] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"296.255111ms","start":"2026-07-23T23:51:19.452418Z","end":"2026-07-23T23:51:19.748673Z","steps":["trace[1077866278] 'process raft request' (duration: 296.069737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.748688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.441287Z","time spent":"307.320251ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:19.748733Z","caller":"traceutil/trace.go:171","msg":"trace[2027853188] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"296.805484ms","start":"2026-07-23T23:51:19.4519Z","end":"2026-07-23T23:51:19.748706Z","steps":["trace[2027853188] 'process raft request' (duration: 296.505158ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:19.748894Z","caller":"traceutil/trace.go:171","msg":"trace[25413278] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"259.404277ms","start":"2026-07-23T23:51:19.489482Z","end":"2026-07-23T23:51:19.748886Z","steps":["trace[25413278] 'process raft request' (duration: 259.10351ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:19.748891Z","caller":"traceutil/trace.go:171","msg":"trace[2089430538] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"268.208347ms","start":"2026-07-23T23:51:19.480671Z","end":"2026-07-23T23:51:19.74888Z","steps":["trace[2089430538] 'process raft request' (duration: 267.865799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.748956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.700347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:30389"} {"level":"info","ts":"2026-07-23T23:51:19.749022Z","caller":"traceutil/trace.go:171","msg":"trace[1890683675] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:517; }","duration":"312.808279ms","start":"2026-07-23T23:51:19.4362Z","end":"2026-07-23T23:51:19.749008Z","steps":["trace[1890683675] 'agreement among raft nodes before linearized reading' (duration: 312.469562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:19.74906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:19.436185Z","time spent":"312.86842ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":30412,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-23T23:51:24.923924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.76554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:24.924066Z","caller":"traceutil/trace.go:171","msg":"trace[323015118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"110.968285ms","start":"2026-07-23T23:51:24.813071Z","end":"2026-07-23T23:51:24.924039Z","steps":["trace[323015118] 'range keys from in-memory index tree' (duration: 110.647087ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:25.266566Z","caller":"traceutil/trace.go:171","msg":"trace[786604362] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"177.594181ms","start":"2026-07-23T23:51:25.088944Z","end":"2026-07-23T23:51:25.266538Z","steps":["trace[786604362] 'process raft request' (duration: 176.918567ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:25.528394Z","caller":"traceutil/trace.go:171","msg":"trace[969404589] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"102.344319ms","start":"2026-07-23T23:51:25.426021Z","end":"2026-07-23T23:51:25.528366Z","steps":["trace[969404589] 'read index received' (duration: 102.111744ms)","trace[969404589] 'applied index is now lower than readState.Index' (duration: 231.475µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:25.528474Z","caller":"traceutil/trace.go:171","msg":"trace[163125075] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"205.209479ms","start":"2026-07-23T23:51:25.323229Z","end":"2026-07-23T23:51:25.528438Z","steps":["trace[163125075] 'process raft request' (duration: 204.964853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:25.528792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.652776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6bzt5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-23T23:51:25.528853Z","caller":"traceutil/trace.go:171","msg":"trace[1869729583] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6bzt5; range_end:; response_count:1; response_revision:591; }","duration":"102.865521ms","start":"2026-07-23T23:51:25.425971Z","end":"2026-07-23T23:51:25.528837Z","steps":["trace[1869729583] 'agreement among raft nodes before linearized reading' (duration: 102.501593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:26.166432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.477746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:26.166522Z","caller":"traceutil/trace.go:171","msg":"trace[902062752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"352.67737ms","start":"2026-07-23T23:51:25.813822Z","end":"2026-07-23T23:51:26.166499Z","steps":["trace[902062752] 'range keys from in-memory index tree' (duration: 352.392304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:26.16672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:25.813798Z","time spent":"352.763082ms","remote":"127.0.0.1:39552","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-23T23:51:26.167039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.855657ms","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-23T23:51:26.167293Z","caller":"traceutil/trace.go:171","msg":"trace[1850392853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"387.162934ms","start":"2026-07-23T23:51:25.780037Z","end":"2026-07-23T23:51:26.1672Z","steps":["trace[1850392853] 'range keys from in-memory index tree' (duration: 386.383407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:26.167462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:25.779977Z","time spent":"387.440631ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-23T23:51:26.204647Z","caller":"traceutil/trace.go:171","msg":"trace[813777482] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"255.107724ms","start":"2026-07-23T23:51:25.949504Z","end":"2026-07-23T23:51:26.204612Z","steps":["trace[813777482] 'process raft request' (duration: 252.384455ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:26.204653Z","caller":"traceutil/trace.go:171","msg":"trace[1760874133] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"197.643405ms","start":"2026-07-23T23:51:26.006958Z","end":"2026-07-23T23:51:26.204602Z","steps":["trace[1760874133] 'read index received' (duration: 190.92987ms)","trace[1760874133] 'applied index is now lower than readState.Index' (duration: 6.712565ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:51:26.204913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.949471ms","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-23T23:51:26.204951Z","caller":"traceutil/trace.go:171","msg":"trace[1863642680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"198.032203ms","start":"2026-07-23T23:51:26.006906Z","end":"2026-07-23T23:51:26.204938Z","steps":["trace[1863642680] 'agreement among raft nodes before linearized reading' (duration: 197.764907ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:40.664057Z","caller":"traceutil/trace.go:171","msg":"trace[127798013] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"126.594612ms","start":"2026-07-23T23:51:40.537436Z","end":"2026-07-23T23:51:40.664031Z","steps":["trace[127798013] 'process raft request' (duration: 126.449169ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:53.257384Z","caller":"traceutil/trace.go:171","msg":"trace[1929322363] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"299.186032ms","start":"2026-07-23T23:52:52.958169Z","end":"2026-07-23T23:52:53.257355Z","steps":["trace[1929322363] 'process raft request' (duration: 298.992238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:53.634817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.655185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T23:52:53.634903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.150788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3642eb9f-f4a2-4d3e-9427-fc679538be78\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-07-23T23:52:53.634929Z","caller":"traceutil/trace.go:171","msg":"trace[236801118] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1802; }","duration":"105.806629ms","start":"2026-07-23T23:52:53.529095Z","end":"2026-07-23T23:52:53.634902Z","steps":["trace[236801118] 'count revisions from in-memory index tree' (duration: 105.583204ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:53.634953Z","caller":"traceutil/trace.go:171","msg":"trace[417237668] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3642eb9f-f4a2-4d3e-9427-fc679538be78; range_end:; response_count:1; response_revision:1802; }","duration":"136.22563ms","start":"2026-07-23T23:52:53.498712Z","end":"2026-07-23T23:52:53.634938Z","steps":["trace[417237668] 'range keys from in-memory index tree' (duration: 135.973185ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:37.805945Z","caller":"traceutil/trace.go:171","msg":"trace[1349700861] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"113.734635ms","start":"2026-07-23T23:53:37.692176Z","end":"2026-07-23T23:53:37.805911Z","steps":["trace[1349700861] 'process raft request' (duration: 113.576942ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:53:38.269169Z","caller":"traceutil/trace.go:171","msg":"trace[176717020] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"123.41466ms","start":"2026-07-23T23:53:38.145725Z","end":"2026-07-23T23:53:38.26914Z","steps":["trace[176717020] 'process raft request' (duration: 123.227256ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:07.454572Z","caller":"traceutil/trace.go:171","msg":"trace[1780696601] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"133.161001ms","start":"2026-07-23T23:55:07.32138Z","end":"2026-07-23T23:55:07.454541Z","steps":["trace[1780696601] 'process raft request' (duration: 132.728462ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:07.667432Z","caller":"traceutil/trace.go:171","msg":"trace[1426001412] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"208.38994ms","start":"2026-07-23T23:55:07.459001Z","end":"2026-07-23T23:55:07.667391Z","steps":["trace[1426001412] 'process raft request' (duration: 133.726422ms)","trace[1426001412] 'compare' (duration: 74.2562ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:55:07.670367Z","caller":"traceutil/trace.go:171","msg":"trace[1167129021] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"210.29968ms","start":"2026-07-23T23:55:07.460051Z","end":"2026-07-23T23:55:07.670351Z","steps":["trace[1167129021] 'process raft request' (duration: 210.26455ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:55:07.67074Z","caller":"traceutil/trace.go:171","msg":"trace[2028344017] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"211.654198ms","start":"2026-07-23T23:55:07.459074Z","end":"2026-07-23T23:55:07.670728Z","steps":["trace[2028344017] 'process raft request' (duration: 211.180608ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:37.863385Z","caller":"traceutil/trace.go:171","msg":"trace[537346819] linearizableReadLoop","detail":"{readStateIndex:3913; appliedIndex:3912; }","duration":"129.686577ms","start":"2026-07-23T23:57:37.733675Z","end":"2026-07-23T23:57:37.863362Z","steps":["trace[537346819] 'read index received' (duration: 129.501533ms)","trace[537346819] 'applied index is now lower than readState.Index' (duration: 183.954µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:37.863624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.926532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tjw76\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-23T23:57:37.863664Z","caller":"traceutil/trace.go:171","msg":"trace[345111515] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tjw76; range_end:; response_count:1; response_revision:3771; }","duration":"130.022663ms","start":"2026-07-23T23:57:37.733631Z","end":"2026-07-23T23:57:37.863654Z","steps":["trace[345111515] 'agreement among raft nodes before linearized reading' (duration: 129.839149ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:37.863968Z","caller":"traceutil/trace.go:171","msg":"trace[30265339] transaction","detail":"{read_only:false; response_revision:3771; number_of_response:1; }","duration":"130.640297ms","start":"2026-07-23T23:57:37.733312Z","end":"2026-07-23T23:57:37.863952Z","steps":["trace[30265339] 'process raft request' (duration: 129.913611ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:59:02.192635Z","caller":"traceutil/trace.go:171","msg":"trace[25295926] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"101.080958ms","start":"2026-07-23T23:59:02.091525Z","end":"2026-07-23T23:59:02.192606Z","steps":["trace[25295926] 'process raft request' (duration: 100.873034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:59:02.19385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.735262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210296"} {"level":"info","ts":"2026-07-23T23:59:02.193938Z","caller":"traceutil/trace.go:171","msg":"trace[1948347167] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4820; }","duration":"100.858214ms","start":"2026-07-23T23:59:02.093056Z","end":"2026-07-23T23:59:02.193914Z","steps":["trace[1948347167] 'agreement among raft nodes before linearized reading' (duration: 99.582187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:59:02.221808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.725305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-23T23:59:02.221902Z","caller":"traceutil/trace.go:171","msg":"trace[1574345154] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4821; }","duration":"105.871737ms","start":"2026-07-23T23:59:02.116005Z","end":"2026-07-23T23:59:02.221877Z","steps":["trace[1574345154] 'agreement among raft nodes before linearized reading' (duration: 105.610992ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:59:02.952083Z","caller":"traceutil/trace.go:171","msg":"trace[282577518] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"117.482656ms","start":"2026-07-23T23:59:02.834572Z","end":"2026-07-23T23:59:02.952054Z","steps":["trace[282577518] 'process raft request' (duration: 100.744651ms)","trace[282577518] 'compare' (duration: 16.609442ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:59:02.956063Z","caller":"traceutil/trace.go:171","msg":"trace[1400187346] linearizableReadLoop","detail":"{readStateIndex:5013; appliedIndex:5011; }","duration":"100.987997ms","start":"2026-07-23T23:59:02.855051Z","end":"2026-07-23T23:59:02.956039Z","steps":["trace[1400187346] 'read index received' (duration: 80.305521ms)","trace[1400187346] 'applied index is now lower than readState.Index' (duration: 20.681206ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:59:02.956121Z","caller":"traceutil/trace.go:171","msg":"trace[1294810137] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"114.115837ms","start":"2026-07-23T23:59:02.841975Z","end":"2026-07-23T23:59:02.956091Z","steps":["trace[1294810137] 'process raft request' (duration: 113.935013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:59:02.956277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.202801ms","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-23T23:59:02.956309Z","caller":"traceutil/trace.go:171","msg":"trace[1305498068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4830; }","duration":"101.278882ms","start":"2026-07-23T23:59:02.855021Z","end":"2026-07-23T23:59:02.9563Z","steps":["trace[1305498068] 'agreement among raft nodes before linearized reading' (duration: 101.117769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:59:02.956327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.092079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-23T23:59:02.956373Z","caller":"traceutil/trace.go:171","msg":"trace[1977204708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4830; }","duration":"101.196702ms","start":"2026-07-23T23:59:02.855166Z","end":"2026-07-23T23:59:02.956363Z","steps":["trace[1977204708] 'agreement among raft nodes before linearized reading' (duration: 101.015438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:59:07.39358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.946417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"warn","ts":"2026-07-23T23:59:07.393651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.083886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b5c31813-39c4-4c4a-9665-ed99c53ae490\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-23T23:59:07.393693Z","caller":"traceutil/trace.go:171","msg":"trace[736849266] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4986; }","duration":"139.128671ms","start":"2026-07-23T23:59:07.254543Z","end":"2026-07-23T23:59:07.393672Z","steps":["trace[736849266] 'range keys from in-memory index tree' (duration: 138.201813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:59:07.393719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.093185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786932"} {"level":"info","ts":"2026-07-23T23:59:07.393836Z","caller":"traceutil/trace.go:171","msg":"trace[744501721] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4986; }","duration":"129.268578ms","start":"2026-07-23T23:59:07.264547Z","end":"2026-07-23T23:59:07.393816Z","steps":["trace[744501721] 'range keys from in-memory index tree' (duration: 128.153765ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:59:07.393753Z","caller":"traceutil/trace.go:171","msg":"trace[125671330] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b5c31813-39c4-4c4a-9665-ed99c53ae490; range_end:; response_count:1; response_revision:4986; }","duration":"148.259389ms","start":"2026-07-23T23:59:07.245475Z","end":"2026-07-23T23:59:07.393734Z","steps":["trace[125671330] 'range keys from in-memory index tree' (duration: 147.250989ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:59:07.737793Z","caller":"traceutil/trace.go:171","msg":"trace[2085687344] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"106.9479ms","start":"2026-07-23T23:59:07.630816Z","end":"2026-07-23T23:59:07.737764Z","steps":["trace[2085687344] 'process raft request' (duration: 106.746646ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:59:19.633298Z","caller":"traceutil/trace.go:171","msg":"trace[1488831685] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"103.347518ms","start":"2026-07-23T23:59:19.529916Z","end":"2026-07-23T23:59:19.633263Z","steps":["trace[1488831685] 'process raft request' (duration: 31.307961ms)","trace[1488831685] 'compare' (duration: 71.68324ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:59:20.33675Z","caller":"traceutil/trace.go:171","msg":"trace[707030871] linearizableReadLoop","detail":"{readStateIndex:5430; appliedIndex:5429; }","duration":"223.164534ms","start":"2026-07-23T23:59:20.113543Z","end":"2026-07-23T23:59:20.336708Z","steps":["trace[707030871] 'read index received' (duration: 222.9741ms)","trace[707030871] 'applied index is now lower than readState.Index' (duration: 189.094µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:59:20.336906Z","caller":"traceutil/trace.go:171","msg":"trace[1342159790] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"298.963377ms","start":"2026-07-23T23:59:20.037895Z","end":"2026-07-23T23:59:20.336858Z","steps":["trace[1342159790] 'process raft request' (duration: 298.64748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:59:20.336983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.42532ms","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-23T23:59:20.337023Z","caller":"traceutil/trace.go:171","msg":"trace[623629947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5234; }","duration":"223.504641ms","start":"2026-07-23T23:59:20.113508Z","end":"2026-07-23T23:59:20.337013Z","steps":["trace[623629947] 'agreement among raft nodes before linearized reading' (duration: 223.362948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:59:20.337258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.076741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:59:20.337386Z","caller":"traceutil/trace.go:171","msg":"trace[1124911341] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:5234; }","duration":"142.318116ms","start":"2026-07-23T23:59:20.195038Z","end":"2026-07-23T23:59:20.337356Z","steps":["trace[1124911341] 'agreement among raft nodes before linearized reading' (duration: 142.04452ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:59:27.576605Z","caller":"traceutil/trace.go:171","msg":"trace[1519474254] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"146.499643ms","start":"2026-07-23T23:59:27.430075Z","end":"2026-07-23T23:59:27.576574Z","steps":["trace[1519474254] 'process raft request' (duration: 133.324323ms)","trace[1519474254] 'compare' (duration: 13.058947ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:59:27.785457Z","caller":"traceutil/trace.go:171","msg":"trace[1272725909] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"118.142351ms","start":"2026-07-23T23:59:27.667286Z","end":"2026-07-23T23:59:27.785428Z","steps":["trace[1272725909] 'process raft request' (duration: 117.942027ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:59:27.944469Z","caller":"traceutil/trace.go:171","msg":"trace[89944078] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"121.832277ms","start":"2026-07-23T23:59:27.822602Z","end":"2026-07-23T23:59:27.944435Z","steps":["trace[89944078] 'process raft request' (duration: 43.972661ms)","trace[89944078] 'compare' (duration: 77.57514ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:00:41.171886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2963} {"level":"info","ts":"2026-07-24T00:00:41.254289Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2963,"took":"79.854018ms","hash":1077142334,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":33656832,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-24T00:00:41.254374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1077142334,"revision":2963,"compact-revision":-1} {"level":"info","ts":"2026-07-24T00:05:19.458873Z","caller":"traceutil/trace.go:171","msg":"trace[1516423827] linearizableReadLoop","detail":"{readStateIndex:8434; appliedIndex:8433; }","duration":"111.073016ms","start":"2026-07-24T00:05:19.347757Z","end":"2026-07-24T00:05:19.45883Z","steps":["trace[1516423827] 'read index received' (duration: 101.102744ms)","trace[1516423827] 'applied index is now lower than readState.Index' (duration: 9.968632ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:05:19.45904Z","caller":"traceutil/trace.go:171","msg":"trace[105868215] transaction","detail":"{read_only:false; response_revision:8151; number_of_response:1; }","duration":"121.157902ms","start":"2026-07-24T00:05:19.33784Z","end":"2026-07-24T00:05:19.458998Z","steps":["trace[105868215] 'process raft request' (duration: 110.913834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:05:19.459138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.314342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T00:05:19.459196Z","caller":"traceutil/trace.go:171","msg":"trace[607296746] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8151; }","duration":"111.479255ms","start":"2026-07-24T00:05:19.347702Z","end":"2026-07-24T00:05:19.459181Z","steps":["trace[607296746] 'agreement among raft nodes before linearized reading' (duration: 111.296911ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:05:31.405806Z","caller":"traceutil/trace.go:171","msg":"trace[1021886909] transaction","detail":"{read_only:false; response_revision:8209; number_of_response:1; }","duration":"242.953596ms","start":"2026-07-24T00:05:31.162828Z","end":"2026-07-24T00:05:31.405782Z","steps":["trace[1021886909] 'process raft request' (duration: 242.17438ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:05:31.432084Z","caller":"traceutil/trace.go:171","msg":"trace[1327891737] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"266.078825ms","start":"2026-07-24T00:05:31.165978Z","end":"2026-07-24T00:05:31.432057Z","steps":["trace[1327891737] 'process raft request' (duration: 266.034433ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:05:31.432257Z","caller":"traceutil/trace.go:171","msg":"trace[1901934983] transaction","detail":"{read_only:false; response_revision:8210; number_of_response:1; }","duration":"267.377441ms","start":"2026-07-24T00:05:31.164814Z","end":"2026-07-24T00:05:31.432192Z","steps":["trace[1901934983] 'process raft request' (duration: 267.041134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:05:32.207703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.605477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-24T00:05:32.207823Z","caller":"traceutil/trace.go:171","msg":"trace[279181830] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8214; }","duration":"208.780661ms","start":"2026-07-24T00:05:31.999013Z","end":"2026-07-24T00:05:32.207793Z","steps":["trace[279181830] 'range keys from in-memory index tree' (duration: 208.340041ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:05:33.043631Z","caller":"traceutil/trace.go:171","msg":"trace[2017690244] linearizableReadLoop","detail":"{readStateIndex:8506; appliedIndex:8505; }","duration":"174.873853ms","start":"2026-07-24T00:05:32.868723Z","end":"2026-07-24T00:05:33.043596Z","steps":["trace[2017690244] 'read index received' (duration: 132.325778ms)","trace[2017690244] 'applied index is now lower than readState.Index' (duration: 42.547175ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:05:33.043696Z","caller":"traceutil/trace.go:171","msg":"trace[758762871] transaction","detail":"{read_only:false; response_revision:8219; number_of_response:1; }","duration":"182.416656ms","start":"2026-07-24T00:05:32.861236Z","end":"2026-07-24T00:05:33.043653Z","steps":["trace[758762871] 'process raft request' (duration: 139.766819ms)","trace[758762871] 'compare' (duration: 42.460933ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:05:33.043864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.122237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tjw76\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-24T00:05:33.043944Z","caller":"traceutil/trace.go:171","msg":"trace[1272457590] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tjw76; range_end:; response_count:1; response_revision:8219; }","duration":"175.23783ms","start":"2026-07-24T00:05:32.868687Z","end":"2026-07-24T00:05:33.043925Z","steps":["trace[1272457590] 'agreement among raft nodes before linearized reading' (duration: 175.034476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:05:33.043962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.24224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-07-24T00:05:33.043985Z","caller":"traceutil/trace.go:171","msg":"trace[1209423983] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8219; }","duration":"172.27712ms","start":"2026-07-24T00:05:32.871699Z","end":"2026-07-24T00:05:33.043976Z","steps":["trace[1209423983] 'agreement among raft nodes before linearized reading' (duration: 172.225489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:05:33.043897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.471374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/key-manager.18c510fcc31b079a\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-07-24T00:05:33.044108Z","caller":"traceutil/trace.go:171","msg":"trace[1848703057] range","detail":"{range_begin:/registry/events/openstack/key-manager.18c510fcc31b079a; range_end:; response_count:1; response_revision:8219; }","duration":"174.734739ms","start":"2026-07-24T00:05:32.869366Z","end":"2026-07-24T00:05:33.044101Z","steps":["trace[1848703057] 'agreement among raft nodes before linearized reading' (duration: 174.386762ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:05:41.180056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6039} {"level":"info","ts":"2026-07-24T00:05:41.316379Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6039,"took":"131.669385ms","hash":1051332351,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":23871488,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-24T00:05:41.316465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1051332351,"revision":6039,"compact-revision":2963} {"level":"info","ts":"2026-07-24T00:06:00.254874Z","caller":"traceutil/trace.go:171","msg":"trace[164096068] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"254.223458ms","start":"2026-07-24T00:06:00.000613Z","end":"2026-07-24T00:06:00.254836Z","steps":["trace[164096068] 'process raft request' (duration: 254.031584ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:06:12.971467Z","caller":"traceutil/trace.go:171","msg":"trace[664601403] linearizableReadLoop","detail":"{readStateIndex:8831; appliedIndex:8830; }","duration":"157.228708ms","start":"2026-07-24T00:06:12.8142Z","end":"2026-07-24T00:06:12.971429Z","steps":["trace[664601403] 'read index received' (duration: 147.773266ms)","trace[664601403] 'applied index is now lower than readState.Index' (duration: 9.454042ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:06:12.971556Z","caller":"traceutil/trace.go:171","msg":"trace[911899834] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"204.144147ms","start":"2026-07-24T00:06:12.767334Z","end":"2026-07-24T00:06:12.971478Z","steps":["trace[911899834] 'process raft request' (duration: 194.657674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:06:12.971718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.490813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-n7czv\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-07-24T00:06:12.971827Z","caller":"traceutil/trace.go:171","msg":"trace[2048777640] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-n7czv; range_end:; response_count:1; response_revision:8530; }","duration":"157.656036ms","start":"2026-07-24T00:06:12.814145Z","end":"2026-07-24T00:06:12.971801Z","steps":["trace[2048777640] 'agreement among raft nodes before linearized reading' (duration: 157.458722ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:06:12.977143Z","caller":"traceutil/trace.go:171","msg":"trace[11552578] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"162.179589ms","start":"2026-07-24T00:06:12.814924Z","end":"2026-07-24T00:06:12.977104Z","steps":["trace[11552578] 'process raft request' (duration: 161.920594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:06:12.977255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.160168ms","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-24T00:06:12.977306Z","caller":"traceutil/trace.go:171","msg":"trace[508167837] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8531; }","duration":"118.25027ms","start":"2026-07-24T00:06:12.859041Z","end":"2026-07-24T00:06:12.977291Z","steps":["trace[508167837] 'agreement among raft nodes before linearized reading' (duration: 118.009026ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:06:13.886293Z","caller":"traceutil/trace.go:171","msg":"trace[722472930] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"134.777684ms","start":"2026-07-24T00:06:13.751484Z","end":"2026-07-24T00:06:13.886262Z","steps":["trace[722472930] 'process raft request' (duration: 134.554119ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:06:13.897108Z","caller":"traceutil/trace.go:171","msg":"trace[827437541] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"136.473678ms","start":"2026-07-24T00:06:13.760608Z","end":"2026-07-24T00:06:13.897082Z","steps":["trace[827437541] 'process raft request' (duration: 135.891807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:06:14.759791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.13217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"warn","ts":"2026-07-24T00:06:14.759808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.412633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T00:06:14.759884Z","caller":"traceutil/trace.go:171","msg":"trace[464900824] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:8545; }","duration":"222.250953ms","start":"2026-07-24T00:06:14.537611Z","end":"2026-07-24T00:06:14.759862Z","steps":["trace[464900824] 'range keys from in-memory index tree' (duration: 221.912255ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:06:14.759898Z","caller":"traceutil/trace.go:171","msg":"trace[462599238] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8545; }","duration":"162.542335ms","start":"2026-07-24T00:06:14.597336Z","end":"2026-07-24T00:06:14.759878Z","steps":["trace[462599238] 'count revisions from in-memory index tree' (duration: 162.316831ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:06:14.789252Z","caller":"traceutil/trace.go:171","msg":"trace[1809819668] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"178.975698ms","start":"2026-07-24T00:06:14.610247Z","end":"2026-07-24T00:06:14.789223Z","steps":["trace[1809819668] 'process raft request' (duration: 178.508617ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:07:01.297547Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002439.wal"} {"level":"info","ts":"2026-07-24T00:08:20.92301Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T00:08:20.929209Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-24T00:08:20.929363Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-24T00:10:41.186965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8270} {"level":"info","ts":"2026-07-24T00:10:41.265807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8270,"took":"75.53986ms","hash":2583400864,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":26525696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-24T00:10:41.26587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2583400864,"revision":8270,"compact-revision":6039} {"level":"info","ts":"2026-07-24T00:11:06.758598Z","caller":"traceutil/trace.go:171","msg":"trace[401151256] transaction","detail":"{read_only:false; response_revision:11094; number_of_response:1; }","duration":"162.529916ms","start":"2026-07-24T00:11:06.596031Z","end":"2026-07-24T00:11:06.758561Z","steps":["trace[401151256] 'process raft request' (duration: 162.335681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:11:07.008985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.859718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T00:11:07.009128Z","caller":"traceutil/trace.go:171","msg":"trace[495288416] linearizableReadLoop","detail":"{readStateIndex:11471; appliedIndex:11470; }","duration":"193.046038ms","start":"2026-07-24T00:11:06.81606Z","end":"2026-07-24T00:11:07.009106Z","steps":["trace[495288416] 'read index received' (duration: 41.875744ms)","trace[495288416] 'applied index is now lower than readState.Index' (duration: 151.168004ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:11:07.009217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.168661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T00:11:07.009245Z","caller":"traceutil/trace.go:171","msg":"trace[1830850012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11095; }","duration":"193.249113ms","start":"2026-07-24T00:11:06.815985Z","end":"2026-07-24T00:11:07.009234Z","steps":["trace[1830850012] 'agreement among raft nodes before linearized reading' (duration: 193.175901ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:11:07.009497Z","caller":"traceutil/trace.go:171","msg":"trace[1728387405] transaction","detail":"{read_only:false; response_revision:11095; number_of_response:1; }","duration":"216.548428ms","start":"2026-07-24T00:11:06.79289Z","end":"2026-07-24T00:11:07.009438Z","steps":["trace[1728387405] 'process raft request' (duration: 65.100958ms)","trace[1728387405] 'compare' (duration: 150.502571ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:15:41.195801Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10915} {"level":"info","ts":"2026-07-24T00:15:41.275613Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10915,"took":"77.099648ms","hash":2656627074,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":28143616,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-24T00:15:41.275677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2656627074,"revision":10915,"compact-revision":8270} {"level":"warn","ts":"2026-07-24T00:17:38.19018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.812799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T00:17:38.190393Z","caller":"traceutil/trace.go:171","msg":"trace[15080959] transaction","detail":"{read_only:false; response_revision:13725; number_of_response:1; }","duration":"444.227853ms","start":"2026-07-24T00:17:37.746144Z","end":"2026-07-24T00:17:38.190372Z","steps":["trace[15080959] 'process raft request' (duration: 444.165141ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:17:38.190502Z","caller":"traceutil/trace.go:171","msg":"trace[27337536] transaction","detail":"{read_only:false; response_revision:13724; number_of_response:1; }","duration":"444.284093ms","start":"2026-07-24T00:17:37.746156Z","end":"2026-07-24T00:17:38.19044Z","steps":["trace[27337536] 'process raft request' (duration: 166.101666ms)","trace[27337536] 'compare' (duration: 277.601315ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:17:38.190691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:17:37.746118Z","time spent":"444.529069ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:17:38.190728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:17:37.74614Z","time spent":"444.469428ms","remote":"127.0.0.1:39826","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-24T00:17:38.232369Z","caller":"traceutil/trace.go:171","msg":"trace[1592250029] transaction","detail":"{read_only:false; response_revision:13726; number_of_response:1; }","duration":"485.857774ms","start":"2026-07-24T00:17:37.746472Z","end":"2026-07-24T00:17:38.232329Z","steps":["trace[1592250029] 'process raft request' (duration: 485.65373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:17:38.23258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:17:37.746456Z","time spent":"486.020757ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:19:15.995677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.292956ms","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-24T00:19:15.995785Z","caller":"traceutil/trace.go:171","msg":"trace[447406190] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14783; }","duration":"202.464221ms","start":"2026-07-24T00:19:15.793293Z","end":"2026-07-24T00:19:15.995757Z","steps":["trace[447406190] 'range keys from in-memory index tree' (duration: 202.115413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:19:15.995959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.062311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-07-24T00:19:15.995968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.317467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-24T00:19:15.996014Z","caller":"traceutil/trace.go:171","msg":"trace[512920220] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14783; }","duration":"180.141873ms","start":"2026-07-24T00:19:15.815858Z","end":"2026-07-24T00:19:15.996Z","steps":["trace[512920220] 'range keys from in-memory index tree' (duration: 179.934529ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:19:15.996028Z","caller":"traceutil/trace.go:171","msg":"trace[1151613768] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14783; }","duration":"160.389008ms","start":"2026-07-24T00:19:15.83562Z","end":"2026-07-24T00:19:15.996009Z","steps":["trace[1151613768] 'range keys from in-memory index tree' (duration: 160.231205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:19:15.996024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.239735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T00:19:15.996139Z","caller":"traceutil/trace.go:171","msg":"trace[1947537904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14783; }","duration":"180.416879ms","start":"2026-07-24T00:19:15.815698Z","end":"2026-07-24T00:19:15.996115Z","steps":["trace[1947537904] 'range keys from in-memory index tree' (duration: 180.123092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:19:15.995967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.734006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T00:19:15.996349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.509202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-24T00:19:15.996686Z","caller":"traceutil/trace.go:171","msg":"trace[904834931] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14783; }","duration":"162.90203ms","start":"2026-07-24T00:19:15.833762Z","end":"2026-07-24T00:19:15.996664Z","steps":["trace[904834931] 'range keys from in-memory index tree' (duration: 162.050503ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:19:15.996896Z","caller":"traceutil/trace.go:171","msg":"trace[723289100] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14783; }","duration":"163.485712ms","start":"2026-07-24T00:19:15.833106Z","end":"2026-07-24T00:19:15.996592Z","steps":["trace[723289100] 'range keys from in-memory index tree' (duration: 162.543282ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:19:16.925632Z","caller":"traceutil/trace.go:171","msg":"trace[1031679430] linearizableReadLoop","detail":"{readStateIndex:15290; appliedIndex:15289; }","duration":"189.202909ms","start":"2026-07-24T00:19:16.736395Z","end":"2026-07-24T00:19:16.925598Z","steps":["trace[1031679430] 'read index received' (duration: 189.007995ms)","trace[1031679430] 'applied index is now lower than readState.Index' (duration: 193.224µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:19:16.925819Z","caller":"traceutil/trace.go:171","msg":"trace[2046901703] transaction","detail":"{read_only:false; response_revision:14787; number_of_response:1; }","duration":"194.551359ms","start":"2026-07-24T00:19:16.731152Z","end":"2026-07-24T00:19:16.925703Z","steps":["trace[2046901703] 'process raft request' (duration: 194.265693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:19:16.925929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.505435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T00:19:16.925974Z","caller":"traceutil/trace.go:171","msg":"trace[379139401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14787; }","duration":"189.595157ms","start":"2026-07-24T00:19:16.736366Z","end":"2026-07-24T00:19:16.925961Z","steps":["trace[379139401] 'agreement among raft nodes before linearized reading' (duration: 189.392363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:19:16.926172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.554406ms","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-24T00:19:16.926208Z","caller":"traceutil/trace.go:171","msg":"trace[1682980379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14787; }","duration":"189.603197ms","start":"2026-07-24T00:19:16.736592Z","end":"2026-07-24T00:19:16.926195Z","steps":["trace[1682980379] 'agreement among raft nodes before linearized reading' (duration: 189.515045ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:19:17.097799Z","caller":"traceutil/trace.go:171","msg":"trace[1145872015] transaction","detail":"{read_only:false; response_revision:14788; number_of_response:1; }","duration":"166.388931ms","start":"2026-07-24T00:19:16.931383Z","end":"2026-07-24T00:19:17.097772Z","steps":["trace[1145872015] 'process raft request' (duration: 103.490152ms)","trace[1145872015] 'compare' (duration: 62.726696ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:19:17.098009Z","caller":"traceutil/trace.go:171","msg":"trace[153796803] transaction","detail":"{read_only:false; response_revision:14789; number_of_response:1; }","duration":"166.052143ms","start":"2026-07-24T00:19:16.931934Z","end":"2026-07-24T00:19:17.097986Z","steps":["trace[153796803] 'process raft request' (duration: 165.794428ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:20:41.202687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12968} {"level":"info","ts":"2026-07-24T00:20:41.27937Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12968,"took":"73.028477ms","hash":4230291847,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":31154176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T00:20:41.279449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4230291847,"revision":12968,"compact-revision":10915} {"level":"info","ts":"2026-07-24T00:24:51.13478Z","caller":"traceutil/trace.go:171","msg":"trace[1908809042] transaction","detail":"{read_only:false; response_revision:17303; number_of_response:1; }","duration":"168.805366ms","start":"2026-07-24T00:24:50.965945Z","end":"2026-07-24T00:24:51.134751Z","steps":["trace[1908809042] 'process raft request' (duration: 168.614812ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:24:51.1366Z","caller":"traceutil/trace.go:171","msg":"trace[182874282] transaction","detail":"{read_only:false; response_revision:17304; number_of_response:1; }","duration":"169.284387ms","start":"2026-07-24T00:24:50.967301Z","end":"2026-07-24T00:24:51.136585Z","steps":["trace[182874282] 'process raft request' (duration: 169.212885ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:24:57.02177Z","caller":"traceutil/trace.go:171","msg":"trace[1620455916] transaction","detail":"{read_only:false; response_revision:17334; number_of_response:1; }","duration":"164.13594ms","start":"2026-07-24T00:24:56.857598Z","end":"2026-07-24T00:24:57.021734Z","steps":["trace[1620455916] 'process raft request' (duration: 163.851044ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:24:57.023247Z","caller":"traceutil/trace.go:171","msg":"trace[2026893295] transaction","detail":"{read_only:false; response_revision:17335; number_of_response:1; }","duration":"159.369844ms","start":"2026-07-24T00:24:56.863796Z","end":"2026-07-24T00:24:57.023166Z","steps":["trace[2026893295] 'process raft request' (duration: 159.18426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:24:57.387033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.281436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-24T00:24:57.387152Z","caller":"traceutil/trace.go:171","msg":"trace[1893665208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17335; }","duration":"218.46189ms","start":"2026-07-24T00:24:57.168669Z","end":"2026-07-24T00:24:57.387131Z","steps":["trace[1893665208] 'range keys from in-memory index tree' (duration: 218.079633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:24:57.387454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.009691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-24T00:24:57.387496Z","caller":"traceutil/trace.go:171","msg":"trace[1549962868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17335; }","duration":"218.100823ms","start":"2026-07-24T00:24:57.169385Z","end":"2026-07-24T00:24:57.387486Z","steps":["trace[1549962868] 'range keys from in-memory index tree' (duration: 217.883038ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:25:41.209663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15349} {"level":"info","ts":"2026-07-24T00:25:41.308254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15349,"took":"94.704319ms","hash":1014455099,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":33533952,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-24T00:25:41.308323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1014455099,"revision":15349,"compact-revision":12968} {"level":"info","ts":"2026-07-24T00:28:57.158141Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T00:28:57.168049Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-24T00:28:57.168171Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-07-24T00:29:39.876058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.845495ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-24T00:29:41.416418Z","caller":"traceutil/trace.go:171","msg":"trace[1176104271] transaction","detail":"{read_only:false; response_revision:19661; number_of_response:1; }","duration":"100.824765ms","start":"2026-07-24T00:29:41.315563Z","end":"2026-07-24T00:29:41.416388Z","steps":["trace[1176104271] 'process raft request' (duration: 100.650682ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:29:45.550838Z","caller":"traceutil/trace.go:171","msg":"trace[533672783] transaction","detail":"{read_only:false; response_revision:19683; number_of_response:1; }","duration":"100.52369ms","start":"2026-07-24T00:29:45.450268Z","end":"2026-07-24T00:29:45.550792Z","steps":["trace[533672783] 'process raft request' (duration: 100.311195ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:29:50.343493Z","caller":"traceutil/trace.go:171","msg":"trace[657932890] linearizableReadLoop","detail":"{readStateIndex:20370; appliedIndex:20369; }","duration":"281.323635ms","start":"2026-07-24T00:29:50.062148Z","end":"2026-07-24T00:29:50.343471Z","steps":["trace[657932890] 'read index received' (duration: 280.980108ms)","trace[657932890] 'applied index is now lower than readState.Index' (duration: 342.527µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:29:50.343572Z","caller":"traceutil/trace.go:171","msg":"trace[1709610521] transaction","detail":"{read_only:false; response_revision:19706; number_of_response:1; }","duration":"317.334029ms","start":"2026-07-24T00:29:50.026193Z","end":"2026-07-24T00:29:50.343527Z","steps":["trace[1709610521] 'process raft request' (duration: 317.113605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:29:50.343686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.524279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-24T00:29:50.343715Z","caller":"traceutil/trace.go:171","msg":"trace[1391900152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19706; }","duration":"281.58802ms","start":"2026-07-24T00:29:50.062118Z","end":"2026-07-24T00:29:50.343706Z","steps":["trace[1391900152] 'agreement among raft nodes before linearized reading' (duration: 281.432607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:29:50.343803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:29:50.026183Z","time spent":"317.503393ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:29:50.490601Z","caller":"traceutil/trace.go:171","msg":"trace[1452054118] transaction","detail":"{read_only:false; response_revision:19708; number_of_response:1; }","duration":"114.078006ms","start":"2026-07-24T00:29:50.376479Z","end":"2026-07-24T00:29:50.490557Z","steps":["trace[1452054118] 'process raft request' (duration: 21.749504ms)","trace[1452054118] 'compare' (duration: 91.826911ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:29:51.014183Z","caller":"traceutil/trace.go:171","msg":"trace[442378164] linearizableReadLoop","detail":"{readStateIndex:20374; appliedIndex:20373; }","duration":"504.772391ms","start":"2026-07-24T00:29:50.509376Z","end":"2026-07-24T00:29:51.014148Z","steps":["trace[442378164] 'read index received' (duration: 436.115221ms)","trace[442378164] 'applied index is now lower than readState.Index' (duration: 68.65577ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:29:51.014207Z","caller":"traceutil/trace.go:171","msg":"trace[127968411] transaction","detail":"{read_only:false; response_revision:19710; number_of_response:1; }","duration":"513.024999ms","start":"2026-07-24T00:29:50.501139Z","end":"2026-07-24T00:29:51.014164Z","steps":["trace[127968411] 'process raft request' (duration: 444.472092ms)","trace[127968411] 'compare' (duration: 68.366494ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:29:51.014396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:29:50.501108Z","time spent":"513.192522ms","remote":"127.0.0.1:54372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7896,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:29:51.014453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.044276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-24T00:29:51.014465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.744869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T00:29:51.014506Z","caller":"traceutil/trace.go:171","msg":"trace[285282867] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:19710; }","duration":"505.176209ms","start":"2026-07-24T00:29:50.509311Z","end":"2026-07-24T00:29:51.014488Z","steps":["trace[285282867] 'agreement among raft nodes before linearized reading' (duration: 505.022926ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:29:51.014558Z","caller":"traceutil/trace.go:171","msg":"trace[1118546385] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:19710; }","duration":"347.891652ms","start":"2026-07-24T00:29:50.666641Z","end":"2026-07-24T00:29:51.014533Z","steps":["trace[1118546385] 'agreement among raft nodes before linearized reading' (duration: 347.719488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:29:51.014531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.403906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-24T00:29:51.014617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:29:50.666616Z","time spent":"347.989784ms","remote":"127.0.0.1:53452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":8,"response size":31,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:29:51.014568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:29:50.509294Z","time spent":"505.25374ms","remote":"127.0.0.1:40092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":43,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-07-24T00:29:51.014878Z","caller":"traceutil/trace.go:171","msg":"trace[1152853064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19710; }","duration":"174.56453ms","start":"2026-07-24T00:29:50.840055Z","end":"2026-07-24T00:29:51.014619Z","steps":["trace[1152853064] 'agreement among raft nodes before linearized reading' (duration: 174.327195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:29:52.558026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.887608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:29:52.55818Z","caller":"traceutil/trace.go:171","msg":"trace[1923257463] transaction","detail":"{read_only:false; response_revision:19719; number_of_response:1; }","duration":"169.805052ms","start":"2026-07-24T00:29:52.388345Z","end":"2026-07-24T00:29:52.55815Z","steps":["trace[1923257463] 'process raft request' (duration: 39.660799ms)","trace[1923257463] 'compare' (duration: 129.683974ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:29:52.618512Z","caller":"traceutil/trace.go:171","msg":"trace[1736477900] transaction","detail":"{read_only:false; response_revision:19720; number_of_response:1; }","duration":"121.841684ms","start":"2026-07-24T00:29:52.496633Z","end":"2026-07-24T00:29:52.618474Z","steps":["trace[1736477900] 'process raft request' (duration: 121.615749ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:30:06.850119Z","caller":"traceutil/trace.go:171","msg":"trace[1369677170] linearizableReadLoop","detail":"{readStateIndex:20480; appliedIndex:20479; }","duration":"109.474773ms","start":"2026-07-24T00:30:06.740601Z","end":"2026-07-24T00:30:06.850076Z","steps":["trace[1369677170] 'read index received' (duration: 72.876786ms)","trace[1369677170] 'applied index is now lower than readState.Index' (duration: 36.596126ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:30:06.85018Z","caller":"traceutil/trace.go:171","msg":"trace[832645015] transaction","detail":"{read_only:false; response_revision:19813; number_of_response:1; }","duration":"180.704314ms","start":"2026-07-24T00:30:06.669446Z","end":"2026-07-24T00:30:06.850151Z","steps":["trace[832645015] 'process raft request' (duration: 144.198419ms)","trace[832645015] 'compare' (duration: 36.212638ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:30:06.85056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.932231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-24T00:30:06.850673Z","caller":"traceutil/trace.go:171","msg":"trace[1255900999] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:19813; }","duration":"110.118245ms","start":"2026-07-24T00:30:06.740534Z","end":"2026-07-24T00:30:06.850652Z","steps":["trace[1255900999] 'agreement among raft nodes before linearized reading' (duration: 109.765768ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:30:28.851913Z","caller":"traceutil/trace.go:171","msg":"trace[1040621346] transaction","detail":"{read_only:false; response_revision:19933; number_of_response:1; }","duration":"107.328057ms","start":"2026-07-24T00:30:28.744538Z","end":"2026-07-24T00:30:28.851866Z","steps":["trace[1040621346] 'process raft request' (duration: 106.992791ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:30:29.141062Z","caller":"traceutil/trace.go:171","msg":"trace[1294793482] transaction","detail":"{read_only:false; response_revision:19934; number_of_response:1; }","duration":"202.223073ms","start":"2026-07-24T00:30:28.938805Z","end":"2026-07-24T00:30:29.141028Z","steps":["trace[1294793482] 'process raft request' (duration: 193.330491ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:30:29.639749Z","caller":"traceutil/trace.go:171","msg":"trace[1126694825] transaction","detail":"{read_only:false; response_revision:19935; number_of_response:1; }","duration":"322.29661ms","start":"2026-07-24T00:30:29.317412Z","end":"2026-07-24T00:30:29.639708Z","steps":["trace[1126694825] 'process raft request' (duration: 322.045065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:30:29.640037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:30:29.317366Z","time spent":"322.491564ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:30:30.15631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.951391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:30:30.156647Z","caller":"traceutil/trace.go:171","msg":"trace[1269710792] transaction","detail":"{read_only:false; response_revision:19936; number_of_response:1; }","duration":"511.543138ms","start":"2026-07-24T00:30:29.64503Z","end":"2026-07-24T00:30:30.156573Z","steps":["trace[1269710792] 'process raft request' (duration: 220.169518ms)","trace[1269710792] 'compare' (duration: 290.765198ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:30:30.156819Z","caller":"traceutil/trace.go:171","msg":"trace[1509958530] transaction","detail":"{read_only:false; response_revision:19937; number_of_response:1; }","duration":"511.6789ms","start":"2026-07-24T00:30:29.645103Z","end":"2026-07-24T00:30:30.156782Z","steps":["trace[1509958530] 'process raft request' (duration: 511.381764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:30:30.156867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:30:29.645006Z","time spent":"511.745951ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:30:30.156858Z","caller":"traceutil/trace.go:171","msg":"trace[1186959944] transaction","detail":"{read_only:false; response_revision:19938; number_of_response:1; }","duration":"508.080718ms","start":"2026-07-24T00:30:29.648732Z","end":"2026-07-24T00:30:30.156812Z","steps":["trace[1186959944] 'process raft request' (duration: 507.876744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:30:30.157001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:30:29.645076Z","time spent":"511.833684ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:30:30.157142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:30:29.648723Z","time spent":"508.237801ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:30:30.309076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.380587ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-24T00:30:30.309825Z","caller":"traceutil/trace.go:171","msg":"trace[1265839826] transaction","detail":"{read_only:false; response_revision:19939; number_of_response:1; }","duration":"485.850566ms","start":"2026-07-24T00:30:29.823939Z","end":"2026-07-24T00:30:30.30979Z","steps":["trace[1265839826] 'process raft request' (duration: 485.243594ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:30:30.309951Z","caller":"traceutil/trace.go:171","msg":"trace[5654213] linearizableReadLoop","detail":"{readStateIndex:20610; appliedIndex:20606; }","duration":"494.43493ms","start":"2026-07-24T00:30:29.815488Z","end":"2026-07-24T00:30:30.309923Z","steps":["trace[5654213] 'read index received' (duration: 49.727924ms)","trace[5654213] 'applied index is now lower than readState.Index' (duration: 444.703856ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:30:30.310101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.601663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T00:30:30.310099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:30:29.823906Z","time spent":"486.044879ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:30:30.310147Z","caller":"traceutil/trace.go:171","msg":"trace[1256563959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19939; }","duration":"494.708326ms","start":"2026-07-24T00:30:29.815425Z","end":"2026-07-24T00:30:30.310133Z","steps":["trace[1256563959] 'agreement among raft nodes before linearized reading' (duration: 494.604544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:30:30.310347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:30:29.815393Z","time spent":"494.941461ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T00:30:30.311028Z","caller":"traceutil/trace.go:171","msg":"trace[147961840] transaction","detail":"{read_only:false; response_revision:19940; number_of_response:1; }","duration":"283.418498ms","start":"2026-07-24T00:30:30.027567Z","end":"2026-07-24T00:30:30.310985Z","steps":["trace[147961840] 'process raft request' (duration: 282.162943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:30:30.54618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.639585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-24T00:30:30.546413Z","caller":"traceutil/trace.go:171","msg":"trace[1568407103] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19940; }","duration":"134.942961ms","start":"2026-07-24T00:30:30.411439Z","end":"2026-07-24T00:30:30.546382Z","steps":["trace[1568407103] 'range keys from in-memory index tree' (duration: 134.314378ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:30:41.217253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17888} {"level":"info","ts":"2026-07-24T00:30:41.353372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17888,"took":"132.316357ms","hash":809981162,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":29917184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-24T00:30:41.353475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":809981162,"revision":17888,"compact-revision":15349} {"level":"info","ts":"2026-07-24T00:35:34.837161Z","caller":"traceutil/trace.go:171","msg":"trace[1176626805] transaction","detail":"{read_only:false; response_revision:23182; number_of_response:1; }","duration":"125.001643ms","start":"2026-07-24T00:35:34.71213Z","end":"2026-07-24T00:35:34.837132Z","steps":["trace[1176626805] 'process raft request' (duration: 124.82957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:35:34.83813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.148294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T00:35:34.838192Z","caller":"traceutil/trace.go:171","msg":"trace[264512114] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:23182; }","duration":"119.263037ms","start":"2026-07-24T00:35:34.718911Z","end":"2026-07-24T00:35:34.838174Z","steps":["trace[264512114] 'agreement among raft nodes before linearized reading' (duration: 119.140885ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:35:34.83801Z","caller":"traceutil/trace.go:171","msg":"trace[1775579610] linearizableReadLoop","detail":"{readStateIndex:23951; appliedIndex:23951; }","duration":"118.89769ms","start":"2026-07-24T00:35:34.718956Z","end":"2026-07-24T00:35:34.837854Z","steps":["trace[1775579610] 'read index received' (duration: 118.8927ms)","trace[1775579610] 'applied index is now lower than readState.Index' (duration: 3.99µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:35:41.223631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19995} {"level":"info","ts":"2026-07-24T00:35:41.334854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19995,"took":"107.180209ms","hash":1464609875,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":43503616,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-24T00:35:41.33514Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1464609875,"revision":19995,"compact-revision":17888} {"level":"warn","ts":"2026-07-24T00:37:30.440021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.269615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:37:30.44024Z","caller":"traceutil/trace.go:171","msg":"trace[1574681454] linearizableReadLoop","detail":"{readStateIndex:25117; appliedIndex:25114; }","duration":"322.787035ms","start":"2026-07-24T00:37:30.117436Z","end":"2026-07-24T00:37:30.440223Z","steps":["trace[1574681454] 'read index received' (duration: 8.535023ms)","trace[1574681454] 'applied index is now lower than readState.Index' (duration: 314.250942ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:30.440288Z","caller":"traceutil/trace.go:171","msg":"trace[1944012185] transaction","detail":"{read_only:false; response_revision:24318; number_of_response:1; }","duration":"327.503482ms","start":"2026-07-24T00:37:30.112771Z","end":"2026-07-24T00:37:30.440274Z","steps":["trace[1944012185] 'process raft request' (duration: 327.359529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:30.440339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:30.112757Z","time spent":"327.557253ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:30.440363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.923809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:30.44033Z","caller":"traceutil/trace.go:171","msg":"trace[1904346984] transaction","detail":"{read_only:false; response_revision:24317; number_of_response:1; }","duration":"331.39551ms","start":"2026-07-24T00:37:30.10888Z","end":"2026-07-24T00:37:30.440275Z","steps":["trace[1904346984] 'process raft request' (duration: 124.769338ms)","trace[1904346984] 'compare' (duration: 205.917908ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:30.44039Z","caller":"traceutil/trace.go:171","msg":"trace[2067281098] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24318; }","duration":"322.994359ms","start":"2026-07-24T00:37:30.117387Z","end":"2026-07-24T00:37:30.440382Z","steps":["trace[2067281098] 'agreement among raft nodes before linearized reading' (duration: 322.941518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:30.440408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:30.117368Z","time spent":"323.036211ms","remote":"127.0.0.1:40028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-24T00:37:30.440505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:30.108865Z","time spent":"331.545154ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:30.45553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.93184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:30.455589Z","caller":"traceutil/trace.go:171","msg":"trace[443178074] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:24319; }","duration":"209.988612ms","start":"2026-07-24T00:37:30.245583Z","end":"2026-07-24T00:37:30.455571Z","steps":["trace[443178074] 'agreement among raft nodes before linearized reading' (duration: 209.881309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:30.68665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.723091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T00:37:30.68678Z","caller":"traceutil/trace.go:171","msg":"trace[1014859632] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24319; }","duration":"137.934655ms","start":"2026-07-24T00:37:30.548802Z","end":"2026-07-24T00:37:30.686737Z","steps":["trace[1014859632] 'range keys from in-memory index tree' (duration: 137.501667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:30.686667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.115907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-24T00:37:30.686869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.988083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T00:37:30.686888Z","caller":"traceutil/trace.go:171","msg":"trace[586121000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24319; }","duration":"132.373882ms","start":"2026-07-24T00:37:30.554485Z","end":"2026-07-24T00:37:30.686858Z","steps":["trace[586121000] 'range keys from in-memory index tree' (duration: 131.882162ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:30.686965Z","caller":"traceutil/trace.go:171","msg":"trace[1712790570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24319; }","duration":"128.120355ms","start":"2026-07-24T00:37:30.558824Z","end":"2026-07-24T00:37:30.686944Z","steps":["trace[1712790570] 'range keys from in-memory index tree' (duration: 127.781658ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:31.080469Z","caller":"traceutil/trace.go:171","msg":"trace[837792071] transaction","detail":"{read_only:false; response_revision:24320; number_of_response:1; }","duration":"389.388961ms","start":"2026-07-24T00:37:30.691055Z","end":"2026-07-24T00:37:31.080444Z","steps":["trace[837792071] 'process raft request' (duration: 389.222437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:31.080624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:30.691036Z","time spent":"389.507153ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:31.371338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.281317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:37:31.371861Z","caller":"traceutil/trace.go:171","msg":"trace[812718277] transaction","detail":"{read_only:false; response_revision:24321; number_of_response:1; }","duration":"679.753476ms","start":"2026-07-24T00:37:30.692067Z","end":"2026-07-24T00:37:31.371821Z","steps":["trace[812718277] 'process raft request' (duration: 438.875847ms)","trace[812718277] 'compare' (duration: 239.332548ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:37:31.372053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:30.692058Z","time spent":"679.885199ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:31.372088Z","caller":"traceutil/trace.go:171","msg":"trace[1641822676] transaction","detail":"{read_only:false; response_revision:24322; number_of_response:1; }","duration":"678.765555ms","start":"2026-07-24T00:37:30.693256Z","end":"2026-07-24T00:37:31.372021Z","steps":["trace[1641822676] 'process raft request' (duration: 678.49156ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:31.372192Z","caller":"traceutil/trace.go:171","msg":"trace[410125809] transaction","detail":"{read_only:false; response_revision:24323; number_of_response:1; }","duration":"325.092382ms","start":"2026-07-24T00:37:31.04707Z","end":"2026-07-24T00:37:31.372162Z","steps":["trace[410125809] 'process raft request' (duration: 324.799666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:31.372303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:31.047039Z","time spent":"325.212865ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:31.372357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:30.69323Z","time spent":"679.005421ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:31.372444Z","caller":"traceutil/trace.go:171","msg":"trace[338249739] linearizableReadLoop","detail":"{readStateIndex:25121; appliedIndex:25118; }","duration":"580.879155ms","start":"2026-07-24T00:37:30.791552Z","end":"2026-07-24T00:37:31.372431Z","steps":["trace[338249739] 'read index received' (duration: 288.689852ms)","trace[338249739] 'applied index is now lower than readState.Index' (duration: 292.187443ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:37:31.372574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.028088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:31.372617Z","caller":"traceutil/trace.go:171","msg":"trace[483654408] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24323; }","duration":"581.12589ms","start":"2026-07-24T00:37:30.791481Z","end":"2026-07-24T00:37:31.372607Z","steps":["trace[483654408] 'agreement among raft nodes before linearized reading' (duration: 581.035108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:31.372644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:30.791459Z","time spent":"581.179301ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:37:31.37277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.913298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:31.372829Z","caller":"traceutil/trace.go:171","msg":"trace[1893567051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24324; }","duration":"558.01512ms","start":"2026-07-24T00:37:30.814797Z","end":"2026-07-24T00:37:31.372812Z","steps":["trace[1893567051] 'agreement among raft nodes before linearized reading' (duration: 557.891198ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:31.372859Z","caller":"traceutil/trace.go:171","msg":"trace[626548550] transaction","detail":"{read_only:false; response_revision:24324; number_of_response:1; }","duration":"292.501389ms","start":"2026-07-24T00:37:31.080348Z","end":"2026-07-24T00:37:31.372849Z","steps":["trace[626548550] 'process raft request' (duration: 291.763104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:31.372872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:30.814774Z","time spent":"558.088681ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T00:37:31.373087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.47828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:31.373116Z","caller":"traceutil/trace.go:171","msg":"trace[460186022] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:24324; }","duration":"498.50591ms","start":"2026-07-24T00:37:30.874601Z","end":"2026-07-24T00:37:31.373107Z","steps":["trace[460186022] 'agreement among raft nodes before linearized reading' (duration: 498.460079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:31.373138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:30.874584Z","time spent":"498.548471ms","remote":"127.0.0.1:45808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:37:31.373324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.796835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-24T00:37:31.373343Z","caller":"traceutil/trace.go:171","msg":"trace[2007096452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24324; }","duration":"175.862617ms","start":"2026-07-24T00:37:31.197474Z","end":"2026-07-24T00:37:31.373336Z","steps":["trace[2007096452] 'agreement among raft nodes before linearized reading' (duration: 175.766765ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:31.661194Z","caller":"traceutil/trace.go:171","msg":"trace[2138487440] transaction","detail":"{read_only:false; response_revision:24325; number_of_response:1; }","duration":"281.652668ms","start":"2026-07-24T00:37:31.379503Z","end":"2026-07-24T00:37:31.661155Z","steps":["trace[2138487440] 'process raft request' (duration: 271.256217ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:31.662495Z","caller":"traceutil/trace.go:171","msg":"trace[442329909] transaction","detail":"{read_only:false; response_revision:24326; number_of_response:1; }","duration":"232.57845ms","start":"2026-07-24T00:37:31.429875Z","end":"2026-07-24T00:37:31.662454Z","steps":["trace[442329909] 'process raft request' (duration: 232.339016ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:31.66263Z","caller":"traceutil/trace.go:171","msg":"trace[1938747408] linearizableReadLoop","detail":"{readStateIndex:25125; appliedIndex:25123; }","duration":"213.80398ms","start":"2026-07-24T00:37:31.448772Z","end":"2026-07-24T00:37:31.662576Z","steps":["trace[1938747408] 'read index received' (duration: 202.02613ms)","trace[1938747408] 'applied index is now lower than readState.Index' (duration: 11.77494ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:37:31.662943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.128676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T00:37:31.662985Z","caller":"traceutil/trace.go:171","msg":"trace[1746559041] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24327; }","duration":"214.223468ms","start":"2026-07-24T00:37:31.448744Z","end":"2026-07-24T00:37:31.662967Z","steps":["trace[1746559041] 'agreement among raft nodes before linearized reading' (duration: 213.988793ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:37.571114Z","caller":"traceutil/trace.go:171","msg":"trace[1526219792] linearizableReadLoop","detail":"{readStateIndex:25171; appliedIndex:25170; }","duration":"174.04352ms","start":"2026-07-24T00:37:37.397024Z","end":"2026-07-24T00:37:37.571068Z","steps":["trace[1526219792] 'read index received' (duration: 173.852446ms)","trace[1526219792] 'applied index is now lower than readState.Index' (duration: 190.014µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:37.571422Z","caller":"traceutil/trace.go:171","msg":"trace[1253554801] transaction","detail":"{read_only:false; response_revision:24371; number_of_response:1; }","duration":"176.217174ms","start":"2026-07-24T00:37:37.395192Z","end":"2026-07-24T00:37:37.571409Z","steps":["trace[1253554801] 'process raft request' (duration: 175.743805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:37.571727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.685343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T00:37:37.571769Z","caller":"traceutil/trace.go:171","msg":"trace[47222449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24371; }","duration":"174.778925ms","start":"2026-07-24T00:37:37.39698Z","end":"2026-07-24T00:37:37.571759Z","steps":["trace[47222449] 'agreement among raft nodes before linearized reading' (duration: 174.627552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:37.571965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.746282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T00:37:37.572018Z","caller":"traceutil/trace.go:171","msg":"trace[1876733890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24371; }","duration":"167.838564ms","start":"2026-07-24T00:37:37.404169Z","end":"2026-07-24T00:37:37.572008Z","steps":["trace[1876733890] 'agreement among raft nodes before linearized reading' (duration: 167.733082ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:37.866298Z","caller":"traceutil/trace.go:171","msg":"trace[190794742] transaction","detail":"{read_only:false; response_revision:24372; number_of_response:1; }","duration":"286.59978ms","start":"2026-07-24T00:37:37.57967Z","end":"2026-07-24T00:37:37.86627Z","steps":["trace[190794742] 'process raft request' (duration: 284.307793ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:37.895791Z","caller":"traceutil/trace.go:171","msg":"trace[1829352969] transaction","detail":"{read_only:false; response_revision:24373; number_of_response:1; }","duration":"316.051119ms","start":"2026-07-24T00:37:37.579706Z","end":"2026-07-24T00:37:37.895757Z","steps":["trace[1829352969] 'process raft request' (duration: 315.801274ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:37.895915Z","caller":"traceutil/trace.go:171","msg":"trace[1675560282] transaction","detail":"{read_only:false; response_revision:24374; number_of_response:1; }","duration":"214.923012ms","start":"2026-07-24T00:37:37.680946Z","end":"2026-07-24T00:37:37.895869Z","steps":["trace[1675560282] 'process raft request' (duration: 214.709988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:37.895959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:37.579682Z","time spent":"316.196241ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:37.896542Z","caller":"traceutil/trace.go:171","msg":"trace[1505976669] transaction","detail":"{read_only:false; response_revision:24375; number_of_response:1; }","duration":"208.372948ms","start":"2026-07-24T00:37:37.688154Z","end":"2026-07-24T00:37:37.896527Z","steps":["trace[1505976669] 'process raft request' (duration: 207.544082ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:37.896788Z","caller":"traceutil/trace.go:171","msg":"trace[1061151012] linearizableReadLoop","detail":"{readStateIndex:25175; appliedIndex:25171; }","duration":"208.262546ms","start":"2026-07-24T00:37:37.688512Z","end":"2026-07-24T00:37:37.896774Z","steps":["trace[1061151012] 'read index received' (duration: 175.446948ms)","trace[1061151012] 'applied index is now lower than readState.Index' (duration: 32.814578ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:37:37.897043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.531331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-24T00:37:37.897121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.456001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T00:37:37.897103Z","caller":"traceutil/trace.go:171","msg":"trace[1419871956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24376; }","duration":"208.593703ms","start":"2026-07-24T00:37:37.688496Z","end":"2026-07-24T00:37:37.89709Z","steps":["trace[1419871956] 'agreement among raft nodes before linearized reading' (duration: 208.411999ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:37.897164Z","caller":"traceutil/trace.go:171","msg":"trace[1365120872] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24376; }","duration":"181.501312ms","start":"2026-07-24T00:37:37.715652Z","end":"2026-07-24T00:37:37.897153Z","steps":["trace[1365120872] 'agreement among raft nodes before linearized reading' (duration: 181.39083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:38.1926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.026632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:37:38.19278Z","caller":"traceutil/trace.go:171","msg":"trace[1223872293] transaction","detail":"{read_only:false; response_revision:24377; number_of_response:1; }","duration":"288.57237ms","start":"2026-07-24T00:37:37.904187Z","end":"2026-07-24T00:37:38.192759Z","steps":["trace[1223872293] 'process raft request' (duration: 138.302543ms)","trace[1223872293] 'compare' (duration: 149.722316ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:38.207165Z","caller":"traceutil/trace.go:171","msg":"trace[197653662] linearizableReadLoop","detail":"{readStateIndex:25178; appliedIndex:25176; }","duration":"184.741158ms","start":"2026-07-24T00:37:38.022397Z","end":"2026-07-24T00:37:38.207139Z","steps":["trace[197653662] 'read index received' (duration: 20.10952ms)","trace[197653662] 'applied index is now lower than readState.Index' (duration: 164.630748ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:37:38.207364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.953702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-24T00:37:38.207342Z","caller":"traceutil/trace.go:171","msg":"trace[930654129] transaction","detail":"{read_only:false; response_revision:24378; number_of_response:1; }","duration":"302.925512ms","start":"2026-07-24T00:37:37.904396Z","end":"2026-07-24T00:37:38.207322Z","steps":["trace[930654129] 'process raft request' (duration: 302.562435ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:38.207403Z","caller":"traceutil/trace.go:171","msg":"trace[1748600554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24378; }","duration":"185.058015ms","start":"2026-07-24T00:37:38.022335Z","end":"2026-07-24T00:37:38.207393Z","steps":["trace[1748600554] 'agreement among raft nodes before linearized reading' (duration: 184.902781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:38.207535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:37.904387Z","time spent":"303.029484ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:38.52466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.131032ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:38.524836Z","caller":"traceutil/trace.go:171","msg":"trace[1404320992] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24379; }","duration":"205.311846ms","start":"2026-07-24T00:37:38.319497Z","end":"2026-07-24T00:37:38.524809Z","steps":["trace[1404320992] 'range keys from in-memory index tree' (duration: 205.112932ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:39.548686Z","caller":"traceutil/trace.go:171","msg":"trace[115752371] linearizableReadLoop","detail":"{readStateIndex:25183; appliedIndex:25182; }","duration":"223.482586ms","start":"2026-07-24T00:37:39.325179Z","end":"2026-07-24T00:37:39.548662Z","steps":["trace[115752371] 'read index received' (duration: 223.346133ms)","trace[115752371] 'applied index is now lower than readState.Index' (duration: 135.243µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:39.548793Z","caller":"traceutil/trace.go:171","msg":"trace[348743897] transaction","detail":"{read_only:false; response_revision:24383; number_of_response:1; }","duration":"335.194768ms","start":"2026-07-24T00:37:39.213557Z","end":"2026-07-24T00:37:39.548752Z","steps":["trace[348743897] 'process raft request' (duration: 334.956674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:39.548845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.65517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:39.548877Z","caller":"traceutil/trace.go:171","msg":"trace[1533728685] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:24383; }","duration":"223.753112ms","start":"2026-07-24T00:37:39.325114Z","end":"2026-07-24T00:37:39.548867Z","steps":["trace[1533728685] 'agreement among raft nodes before linearized reading' (duration: 223.68124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:39.548969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:39.213542Z","time spent":"335.331761ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:40.022485Z","caller":"traceutil/trace.go:171","msg":"trace[994432574] linearizableReadLoop","detail":"{readStateIndex:25185; appliedIndex:25184; }","duration":"151.061483ms","start":"2026-07-24T00:37:39.871386Z","end":"2026-07-24T00:37:40.022448Z","steps":["trace[994432574] 'read index received' (duration: 109.65759ms)","trace[994432574] 'applied index is now lower than readState.Index' (duration: 41.399903ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:40.022654Z","caller":"traceutil/trace.go:171","msg":"trace[1438673521] transaction","detail":"{read_only:false; response_revision:24385; number_of_response:1; }","duration":"120.683785ms","start":"2026-07-24T00:37:39.90194Z","end":"2026-07-24T00:37:40.022624Z","steps":["trace[1438673521] 'process raft request' (duration: 120.40399ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:40.023026Z","caller":"traceutil/trace.go:171","msg":"trace[1073433665] transaction","detail":"{read_only:false; response_revision:24387; number_of_response:1; }","duration":"115.767215ms","start":"2026-07-24T00:37:39.90723Z","end":"2026-07-24T00:37:40.022997Z","steps":["trace[1073433665] 'process raft request' (duration: 115.595281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:40.023044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.636644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T00:37:40.023318Z","caller":"traceutil/trace.go:171","msg":"trace[1233794053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24386; }","duration":"151.95911ms","start":"2026-07-24T00:37:39.871334Z","end":"2026-07-24T00:37:40.023293Z","steps":["trace[1233794053] 'agreement among raft nodes before linearized reading' (duration: 151.223206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:40.023362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.177428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T00:37:40.023416Z","caller":"traceutil/trace.go:171","msg":"trace[1442293826] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24387; }","duration":"105.257271ms","start":"2026-07-24T00:37:39.918141Z","end":"2026-07-24T00:37:40.023398Z","steps":["trace[1442293826] 'agreement among raft nodes before linearized reading' (duration: 105.071007ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:40.023471Z","caller":"traceutil/trace.go:171","msg":"trace[103769437] transaction","detail":"{read_only:false; response_revision:24386; number_of_response:1; }","duration":"118.953359ms","start":"2026-07-24T00:37:39.904499Z","end":"2026-07-24T00:37:40.023452Z","steps":["trace[103769437] 'process raft request' (duration: 118.046731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:40.023063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.837155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T00:37:40.023725Z","caller":"traceutil/trace.go:171","msg":"trace[609793108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24387; }","duration":"116.515349ms","start":"2026-07-24T00:37:39.907161Z","end":"2026-07-24T00:37:40.023676Z","steps":["trace[609793108] 'agreement among raft nodes before linearized reading' (duration: 115.771184ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:40.195041Z","caller":"traceutil/trace.go:171","msg":"trace[1813291996] transaction","detail":"{read_only:false; response_revision:24388; number_of_response:1; }","duration":"165.264431ms","start":"2026-07-24T00:37:40.029753Z","end":"2026-07-24T00:37:40.195017Z","steps":["trace[1813291996] 'process raft request' (duration: 160.453604ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:40.207501Z","caller":"traceutil/trace.go:171","msg":"trace[101724951] transaction","detail":"{read_only:false; response_revision:24389; number_of_response:1; }","duration":"176.930639ms","start":"2026-07-24T00:37:40.030377Z","end":"2026-07-24T00:37:40.207307Z","steps":["trace[101724951] 'process raft request' (duration: 176.053731ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:40.378147Z","caller":"traceutil/trace.go:171","msg":"trace[1632581123] linearizableReadLoop","detail":"{readStateIndex:25193; appliedIndex:25191; }","duration":"157.598195ms","start":"2026-07-24T00:37:40.220525Z","end":"2026-07-24T00:37:40.378124Z","steps":["trace[1632581123] 'read index received' (duration: 145.041609ms)","trace[1632581123] 'applied index is now lower than readState.Index' (duration: 12.555426ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:40.37841Z","caller":"traceutil/trace.go:171","msg":"trace[933953479] transaction","detail":"{read_only:false; response_revision:24391; number_of_response:1; }","duration":"165.522667ms","start":"2026-07-24T00:37:40.212873Z","end":"2026-07-24T00:37:40.378396Z","steps":["trace[933953479] 'process raft request' (duration: 152.695175ms)","trace[933953479] 'compare' (duration: 12.382673ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:40.378575Z","caller":"traceutil/trace.go:171","msg":"trace[1166768432] transaction","detail":"{read_only:false; response_revision:24392; number_of_response:1; }","duration":"162.663368ms","start":"2026-07-24T00:37:40.215903Z","end":"2026-07-24T00:37:40.378567Z","steps":["trace[1166768432] 'process raft request' (duration: 162.178538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:40.378782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.239528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-24T00:37:40.378817Z","caller":"traceutil/trace.go:171","msg":"trace[1299662590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24392; }","duration":"158.32002ms","start":"2026-07-24T00:37:40.220489Z","end":"2026-07-24T00:37:40.378809Z","steps":["trace[1299662590] 'agreement among raft nodes before linearized reading' (duration: 158.190617ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:40.75864Z","caller":"traceutil/trace.go:171","msg":"trace[1971290314] transaction","detail":"{read_only:false; response_revision:24394; number_of_response:1; }","duration":"226.697931ms","start":"2026-07-24T00:37:40.531918Z","end":"2026-07-24T00:37:40.758616Z","steps":["trace[1971290314] 'process raft request' (duration: 226.541078ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:40.881612Z","caller":"traceutil/trace.go:171","msg":"trace[1562541688] transaction","detail":"{read_only:false; response_revision:24395; number_of_response:1; }","duration":"341.417165ms","start":"2026-07-24T00:37:40.540171Z","end":"2026-07-24T00:37:40.881588Z","steps":["trace[1562541688] 'process raft request' (duration: 286.837175ms)","trace[1562541688] 'compare' (duration: 54.336125ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:40.881717Z","caller":"traceutil/trace.go:171","msg":"trace[441986890] linearizableReadLoop","detail":"{readStateIndex:25197; appliedIndex:25194; }","duration":"305.826742ms","start":"2026-07-24T00:37:40.575873Z","end":"2026-07-24T00:37:40.8817Z","steps":["trace[441986890] 'read index received' (duration: 182.624206ms)","trace[441986890] 'applied index is now lower than readState.Index' (duration: 123.200816ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:37:40.881748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:40.540149Z","time spent":"341.523647ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:40.881852Z","caller":"traceutil/trace.go:171","msg":"trace[548802763] transaction","detail":"{read_only:false; response_revision:24396; number_of_response:1; }","duration":"341.646389ms","start":"2026-07-24T00:37:40.540199Z","end":"2026-07-24T00:37:40.881846Z","steps":["trace[548802763] 'process raft request' (duration: 341.348424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:40.881893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:40.540179Z","time spent":"341.6945ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:40.881899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.035645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-24T00:37:40.881931Z","caller":"traceutil/trace.go:171","msg":"trace[55354169] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24396; }","duration":"306.072166ms","start":"2026-07-24T00:37:40.57585Z","end":"2026-07-24T00:37:40.881922Z","steps":["trace[55354169] 'agreement among raft nodes before linearized reading' (duration: 305.909673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:40.881971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:40.575811Z","time spent":"306.153788ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-24T00:37:41.853752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.598136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:37:41.853878Z","caller":"traceutil/trace.go:171","msg":"trace[1248021426] transaction","detail":"{read_only:false; response_revision:24398; number_of_response:1; }","duration":"276.881052ms","start":"2026-07-24T00:37:41.576973Z","end":"2026-07-24T00:37:41.853854Z","steps":["trace[1248021426] 'process raft request' (duration: 150.078722ms)","trace[1248021426] 'compare' (duration: 126.472872ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:41.942115Z","caller":"traceutil/trace.go:171","msg":"trace[1713735565] linearizableReadLoop","detail":"{readStateIndex:25200; appliedIndex:25199; }","duration":"192.440424ms","start":"2026-07-24T00:37:41.749651Z","end":"2026-07-24T00:37:41.942092Z","steps":["trace[1713735565] 'read index received' (duration: 192.193069ms)","trace[1713735565] 'applied index is now lower than readState.Index' (duration: 246.585µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:41.942351Z","caller":"traceutil/trace.go:171","msg":"trace[1775545172] transaction","detail":"{read_only:false; response_revision:24399; number_of_response:1; }","duration":"265.097011ms","start":"2026-07-24T00:37:41.677246Z","end":"2026-07-24T00:37:41.942343Z","steps":["trace[1775545172] 'process raft request' (duration: 264.721284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:41.942515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.846903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:41.942546Z","caller":"traceutil/trace.go:171","msg":"trace[1312690216] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:24399; }","duration":"192.938365ms","start":"2026-07-24T00:37:41.749598Z","end":"2026-07-24T00:37:41.942537Z","steps":["trace[1312690216] 'agreement among raft nodes before linearized reading' (duration: 192.875724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:41.942659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.402811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:41.942679Z","caller":"traceutil/trace.go:171","msg":"trace[1539540581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24399; }","duration":"127.449012ms","start":"2026-07-24T00:37:41.815225Z","end":"2026-07-24T00:37:41.942674Z","steps":["trace[1539540581] 'agreement among raft nodes before linearized reading' (duration: 127.418021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:42.204123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.726218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:37:42.204304Z","caller":"traceutil/trace.go:171","msg":"trace[1624205800] transaction","detail":"{read_only:false; response_revision:24400; number_of_response:1; }","duration":"202.142472ms","start":"2026-07-24T00:37:42.00214Z","end":"2026-07-24T00:37:42.204282Z","steps":["trace[1624205800] 'process raft request' (duration: 18.170089ms)","trace[1624205800] 'compare' (duration: 183.418781ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:42.204503Z","caller":"traceutil/trace.go:171","msg":"trace[1047891922] transaction","detail":"{read_only:false; response_revision:24401; number_of_response:1; }","duration":"175.581911ms","start":"2026-07-24T00:37:42.028886Z","end":"2026-07-24T00:37:42.204468Z","steps":["trace[1047891922] 'process raft request' (duration: 175.354427ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:42.208291Z","caller":"traceutil/trace.go:171","msg":"trace[1233000685] transaction","detail":"{read_only:false; response_revision:24402; number_of_response:1; }","duration":"177.719544ms","start":"2026-07-24T00:37:42.030553Z","end":"2026-07-24T00:37:42.208272Z","steps":["trace[1233000685] 'process raft request' (duration: 177.232724ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:42.208453Z","caller":"traceutil/trace.go:171","msg":"trace[1475636727] transaction","detail":"{read_only:false; response_revision:24403; number_of_response:1; }","duration":"177.731175ms","start":"2026-07-24T00:37:42.030712Z","end":"2026-07-24T00:37:42.208443Z","steps":["trace[1475636727] 'process raft request' (duration: 177.154423ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:42.493873Z","caller":"traceutil/trace.go:171","msg":"trace[1533947110] transaction","detail":"{read_only:false; response_revision:24404; number_of_response:1; }","duration":"278.952534ms","start":"2026-07-24T00:37:42.214885Z","end":"2026-07-24T00:37:42.493837Z","steps":["trace[1533947110] 'process raft request' (duration: 278.75479ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:42.539518Z","caller":"traceutil/trace.go:171","msg":"trace[1645529305] linearizableReadLoop","detail":"{readStateIndex:25206; appliedIndex:25204; }","duration":"205.071691ms","start":"2026-07-24T00:37:42.334417Z","end":"2026-07-24T00:37:42.539489Z","steps":["trace[1645529305] 'read index received' (duration: 159.223079ms)","trace[1645529305] 'applied index is now lower than readState.Index' (duration: 45.847252ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:37:42.539833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.071044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-24T00:37:42.539875Z","caller":"traceutil/trace.go:171","msg":"trace[1420823308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24405; }","duration":"154.172666ms","start":"2026-07-24T00:37:42.385691Z","end":"2026-07-24T00:37:42.539864Z","steps":["trace[1420823308] 'agreement among raft nodes before linearized reading' (duration: 153.966151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:42.539838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.400717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-24T00:37:42.539961Z","caller":"traceutil/trace.go:171","msg":"trace[417056065] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24405; }","duration":"205.544781ms","start":"2026-07-24T00:37:42.334397Z","end":"2026-07-24T00:37:42.539942Z","steps":["trace[417056065] 'agreement among raft nodes before linearized reading' (duration: 205.255844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:42.539953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.622908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-24T00:37:42.540053Z","caller":"traceutil/trace.go:171","msg":"trace[1926993753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24405; }","duration":"133.802572ms","start":"2026-07-24T00:37:42.406226Z","end":"2026-07-24T00:37:42.540029Z","steps":["trace[1926993753] 'agreement among raft nodes before linearized reading' (duration: 133.483954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:42.54008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.156475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-24T00:37:42.540176Z","caller":"traceutil/trace.go:171","msg":"trace[1268548429] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24405; }","duration":"152.255777ms","start":"2026-07-24T00:37:42.387893Z","end":"2026-07-24T00:37:42.540149Z","steps":["trace[1268548429] 'agreement among raft nodes before linearized reading' (duration: 152.027153ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:42.540357Z","caller":"traceutil/trace.go:171","msg":"trace[206422775] transaction","detail":"{read_only:false; response_revision:24405; number_of_response:1; }","duration":"325.016561ms","start":"2026-07-24T00:37:42.215323Z","end":"2026-07-24T00:37:42.54034Z","steps":["trace[206422775] 'process raft request' (duration: 323.01437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:42.540508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:42.215316Z","time spent":"325.111523ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:42.839564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.901205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:37:42.841752Z","caller":"traceutil/trace.go:171","msg":"trace[1412430510] transaction","detail":"{read_only:false; response_revision:24407; number_of_response:1; }","duration":"293.99531ms","start":"2026-07-24T00:37:42.54773Z","end":"2026-07-24T00:37:42.841725Z","steps":["trace[1412430510] 'process raft request' (duration: 180.773757ms)","trace[1412430510] 'compare' (duration: 110.415306ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:42.842145Z","caller":"traceutil/trace.go:171","msg":"trace[1266999367] transaction","detail":"{read_only:false; response_revision:24408; number_of_response:1; }","duration":"294.320167ms","start":"2026-07-24T00:37:42.547797Z","end":"2026-07-24T00:37:42.842117Z","steps":["trace[1266999367] 'process raft request' (duration: 293.440319ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:43.211261Z","caller":"traceutil/trace.go:171","msg":"trace[1173314419] transaction","detail":"{read_only:false; response_revision:24410; number_of_response:1; }","duration":"320.614631ms","start":"2026-07-24T00:37:42.890427Z","end":"2026-07-24T00:37:43.211042Z","steps":["trace[1173314419] 'process raft request' (duration: 320.484538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:43.211402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:42.890403Z","time spent":"320.922867ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:43.238023Z","caller":"traceutil/trace.go:171","msg":"trace[995081126] transaction","detail":"{read_only:false; response_revision:24411; number_of_response:1; }","duration":"346.249674ms","start":"2026-07-24T00:37:42.891749Z","end":"2026-07-24T00:37:43.237999Z","steps":["trace[995081126] 'process raft request' (duration: 345.877016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:43.238129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:42.891723Z","time spent":"346.353085ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:48.097269Z","caller":"traceutil/trace.go:171","msg":"trace[2003292032] transaction","detail":"{read_only:false; response_revision:24441; number_of_response:1; }","duration":"206.697494ms","start":"2026-07-24T00:37:47.890541Z","end":"2026-07-24T00:37:48.097238Z","steps":["trace[2003292032] 'process raft request' (duration: 206.51253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:48.720689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.60695ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:48.720804Z","caller":"traceutil/trace.go:171","msg":"trace[570930124] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24441; }","duration":"401.742692ms","start":"2026-07-24T00:37:48.319044Z","end":"2026-07-24T00:37:48.720787Z","steps":["trace[570930124] 'range keys from in-memory index tree' (duration: 401.587309ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:37:48.721447Z","caller":"traceutil/trace.go:171","msg":"trace[2048707034] linearizableReadLoop","detail":"{readStateIndex:25244; appliedIndex:25242; }","duration":"708.487981ms","start":"2026-07-24T00:37:48.012932Z","end":"2026-07-24T00:37:48.72142Z","steps":["trace[2048707034] 'read index received' (duration: 84.187582ms)","trace[2048707034] 'applied index is now lower than readState.Index' (duration: 624.298929ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:48.721473Z","caller":"traceutil/trace.go:171","msg":"trace[1998640633] transaction","detail":"{read_only:false; response_revision:24442; number_of_response:1; }","duration":"746.171317ms","start":"2026-07-24T00:37:47.975265Z","end":"2026-07-24T00:37:48.721437Z","steps":["trace[1998640633] 'process raft request' (duration: 733.796666ms)","trace[1998640633] 'compare' (duration: 11.632167ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:37:48.721723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:47.975236Z","time spent":"746.395201ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:48.721792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.828888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:48.721887Z","caller":"traceutil/trace.go:171","msg":"trace[823354721] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24442; }","duration":"708.996071ms","start":"2026-07-24T00:37:48.01287Z","end":"2026-07-24T00:37:48.721866Z","steps":["trace[823354721] 'agreement among raft nodes before linearized reading' (duration: 708.656664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:48.721943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.01285Z","time spent":"709.081043ms","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-07-24T00:37:48.734536Z","caller":"traceutil/trace.go:171","msg":"trace[1517369934] transaction","detail":"{read_only:false; response_revision:24445; number_of_response:1; }","duration":"505.011122ms","start":"2026-07-24T00:37:48.229509Z","end":"2026-07-24T00:37:48.73452Z","steps":["trace[1517369934] 'process raft request' (duration: 504.976911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:48.734619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.22949Z","time spent":"505.088814ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:48.734937Z","caller":"traceutil/trace.go:171","msg":"trace[1121799564] transaction","detail":"{read_only:false; response_revision:24443; number_of_response:1; }","duration":"508.359401ms","start":"2026-07-24T00:37:48.226568Z","end":"2026-07-24T00:37:48.734927Z","steps":["trace[1121799564] 'process raft request' (duration: 507.735409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:48.734984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.226532Z","time spent":"508.425452ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:48.735179Z","caller":"traceutil/trace.go:171","msg":"trace[1788719874] transaction","detail":"{read_only:false; response_revision:24444; number_of_response:1; }","duration":"507.939331ms","start":"2026-07-24T00:37:48.227145Z","end":"2026-07-24T00:37:48.735084Z","steps":["trace[1788719874] 'process raft request' (duration: 507.293248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:48.735265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.22712Z","time spent":"508.102525ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:48.735794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.464039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:48.735841Z","caller":"traceutil/trace.go:171","msg":"trace[303430135] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24445; }","duration":"706.539231ms","start":"2026-07-24T00:37:48.029288Z","end":"2026-07-24T00:37:48.735827Z","steps":["trace[303430135] 'agreement among raft nodes before linearized reading' (duration: 706.46504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:48.735876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.029273Z","time spent":"706.591852ms","remote":"127.0.0.1:54274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:37:48.736288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.009945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T00:37:48.736322Z","caller":"traceutil/trace.go:171","msg":"trace[2026033124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24445; }","duration":"160.073086ms","start":"2026-07-24T00:37:48.576239Z","end":"2026-07-24T00:37:48.736312Z","steps":["trace[2026033124] 'agreement among raft nodes before linearized reading' (duration: 159.991224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:48.736493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.731588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T00:37:48.736519Z","caller":"traceutil/trace.go:171","msg":"trace[327534868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24445; }","duration":"216.8062ms","start":"2026-07-24T00:37:48.519704Z","end":"2026-07-24T00:37:48.73651Z","steps":["trace[327534868] 'agreement among raft nodes before linearized reading' (duration: 216.736078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:48.736766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.903833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:48.736843Z","caller":"traceutil/trace.go:171","msg":"trace[1050977928] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24445; }","duration":"275.008885ms","start":"2026-07-24T00:37:48.461815Z","end":"2026-07-24T00:37:48.736824Z","steps":["trace[1050977928] 'agreement among raft nodes before linearized reading' (duration: 274.836581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:48.736994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.988781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:48.737033Z","caller":"traceutil/trace.go:171","msg":"trace[1457098533] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:24445; }","duration":"558.072552ms","start":"2026-07-24T00:37:48.17895Z","end":"2026-07-24T00:37:48.737023Z","steps":["trace[1457098533] 'agreement among raft nodes before linearized reading' (duration: 558.011101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:48.737062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.17893Z","time spent":"558.125684ms","remote":"127.0.0.1:54338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:37:49.301523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.356281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:37:49.301879Z","caller":"traceutil/trace.go:171","msg":"trace[1287921303] transaction","detail":"{read_only:false; response_revision:24446; number_of_response:1; }","duration":"561.260626ms","start":"2026-07-24T00:37:48.740569Z","end":"2026-07-24T00:37:49.301829Z","steps":["trace[1287921303] 'process raft request' (duration: 282.478166ms)","trace[1287921303] 'compare' (duration: 277.942463ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:49.30197Z","caller":"traceutil/trace.go:171","msg":"trace[812757693] linearizableReadLoop","detail":"{readStateIndex:25250; appliedIndex:25247; }","duration":"486.169619ms","start":"2026-07-24T00:37:48.815774Z","end":"2026-07-24T00:37:49.301943Z","steps":["trace[812757693] 'read index received' (duration: 207.576323ms)","trace[812757693] 'applied index is now lower than readState.Index' (duration: 278.591436ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:37:49.302024Z","caller":"traceutil/trace.go:171","msg":"trace[976214720] transaction","detail":"{read_only:false; response_revision:24449; number_of_response:1; }","duration":"437.032169ms","start":"2026-07-24T00:37:48.864959Z","end":"2026-07-24T00:37:49.301991Z","steps":["trace[976214720] 'process raft request' (duration: 436.918187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:49.302065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.74054Z","time spent":"561.427929ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:49.302032Z","caller":"traceutil/trace.go:171","msg":"trace[819036384] transaction","detail":"{read_only:false; response_revision:24448; number_of_response:1; }","duration":"560.679614ms","start":"2026-07-24T00:37:48.741288Z","end":"2026-07-24T00:37:49.301968Z","steps":["trace[819036384] 'process raft request' (duration: 560.530431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:49.302118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.327672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:49.302118Z","caller":"traceutil/trace.go:171","msg":"trace[1180043057] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"561.135123ms","start":"2026-07-24T00:37:48.740926Z","end":"2026-07-24T00:37:49.302061Z","steps":["trace[1180043057] 'process raft request' (duration: 560.741845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:49.302173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.864925Z","time spent":"437.168132ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:37:49.302199Z","caller":"traceutil/trace.go:171","msg":"trace[594971405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24449; }","duration":"486.461645ms","start":"2026-07-24T00:37:48.815719Z","end":"2026-07-24T00:37:49.302181Z","steps":["trace[594971405] 'agreement among raft nodes before linearized reading' (duration: 486.334152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:49.302241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.815695Z","time spent":"486.539156ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T00:37:49.302305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.74128Z","time spent":"560.868528ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:49.302378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.740917Z","time spent":"561.337227ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:37:49.303046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.676813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:37:49.303111Z","caller":"traceutil/trace.go:171","msg":"trace[1115851771] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24451; }","duration":"142.785555ms","start":"2026-07-24T00:37:49.160295Z","end":"2026-07-24T00:37:49.303081Z","steps":["trace[1115851771] 'agreement among raft nodes before linearized reading' (duration: 142.680903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:49.303138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.888938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-24T00:37:49.303367Z","caller":"traceutil/trace.go:171","msg":"trace[423766390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24451; }","duration":"438.177293ms","start":"2026-07-24T00:37:48.865177Z","end":"2026-07-24T00:37:49.303354Z","steps":["trace[423766390] 'agreement among raft nodes before linearized reading' (duration: 437.796625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:49.303403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.865144Z","time spent":"438.252275ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T00:37:49.303473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.916167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-07-24T00:37:49.30358Z","caller":"traceutil/trace.go:171","msg":"trace[917616876] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24451; }","duration":"378.08089ms","start":"2026-07-24T00:37:48.925473Z","end":"2026-07-24T00:37:49.303553Z","steps":["trace[917616876] 'agreement among raft nodes before linearized reading' (duration: 377.56039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:49.303644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.925442Z","time spent":"378.186633ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-24T00:37:49.303919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.936487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-24T00:37:49.303978Z","caller":"traceutil/trace.go:171","msg":"trace[1897582059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24451; }","duration":"438.025609ms","start":"2026-07-24T00:37:48.865938Z","end":"2026-07-24T00:37:49.303964Z","steps":["trace[1897582059] 'agreement among raft nodes before linearized reading' (duration: 437.608111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:37:49.304051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:37:48.86592Z","time spent":"438.120461ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T00:37:49.304131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.439914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274397"} {"level":"info","ts":"2026-07-24T00:37:49.304183Z","caller":"traceutil/trace.go:171","msg":"trace[508039434] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:24451; }","duration":"225.531716ms","start":"2026-07-24T00:37:49.078636Z","end":"2026-07-24T00:37:49.304168Z","steps":["trace[508039434] 'agreement among raft nodes before linearized reading' (duration: 225.007866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:00.480601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.635243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:38:00.480732Z","caller":"traceutil/trace.go:171","msg":"trace[740461237] linearizableReadLoop","detail":"{readStateIndex:25393; appliedIndex:25392; }","duration":"145.959078ms","start":"2026-07-24T00:38:00.334753Z","end":"2026-07-24T00:38:00.480712Z","steps":["trace[740461237] 'read index received' (duration: 2.094241ms)","trace[740461237] 'applied index is now lower than readState.Index' (duration: 143.863557ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:00.480804Z","caller":"traceutil/trace.go:171","msg":"trace[1552994717] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"241.694566ms","start":"2026-07-24T00:38:00.239057Z","end":"2026-07-24T00:38:00.480752Z","steps":["trace[1552994717] 'process raft request' (duration: 97.796019ms)","trace[1552994717] 'compare' (duration: 143.457908ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:00.480979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.202774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-38b95e25-3d62-442e-aa92-69542e98964d\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-24T00:38:00.481064Z","caller":"traceutil/trace.go:171","msg":"trace[670440876] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-38b95e25-3d62-442e-aa92-69542e98964d; range_end:; response_count:1; response_revision:24588; }","duration":"146.348237ms","start":"2026-07-24T00:38:00.334694Z","end":"2026-07-24T00:38:00.481042Z","steps":["trace[670440876] 'agreement among raft nodes before linearized reading' (duration: 146.104262ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:00.74251Z","caller":"traceutil/trace.go:171","msg":"trace[530447785] transaction","detail":"{read_only:false; response_revision:24589; number_of_response:1; }","duration":"256.404205ms","start":"2026-07-24T00:38:00.486086Z","end":"2026-07-24T00:38:00.74249Z","steps":["trace[530447785] 'process raft request' (duration: 256.275472ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:00.912275Z","caller":"traceutil/trace.go:171","msg":"trace[1629701959] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"130.166878ms","start":"2026-07-24T00:38:00.782081Z","end":"2026-07-24T00:38:00.912247Z","steps":["trace[1629701959] 'process raft request' (duration: 129.996445ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:01.265337Z","caller":"traceutil/trace.go:171","msg":"trace[157430279] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"482.79084ms","start":"2026-07-24T00:38:00.782523Z","end":"2026-07-24T00:38:01.265314Z","steps":["trace[157430279] 'process raft request' (duration: 389.114624ms)","trace[157430279] 'compare' (duration: 93.38118ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:01.265555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:00.782517Z","time spent":"482.963453ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:38:01.265618Z","caller":"traceutil/trace.go:171","msg":"trace[895408005] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"483.027764ms","start":"2026-07-24T00:38:00.78256Z","end":"2026-07-24T00:38:01.265588Z","steps":["trace[895408005] 'process raft request' (duration: 482.709888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.265701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:00.782557Z","time spent":"483.091305ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:38:01.265729Z","caller":"traceutil/trace.go:171","msg":"trace[1758045821] transaction","detail":"{read_only:false; response_revision:24593; number_of_response:1; }","duration":"482.899582ms","start":"2026-07-24T00:38:00.782803Z","end":"2026-07-24T00:38:01.265703Z","steps":["trace[1758045821] 'process raft request' (duration: 482.752009ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:01.265725Z","caller":"traceutil/trace.go:171","msg":"trace[109047822] linearizableReadLoop","detail":"{readStateIndex:25398; appliedIndex:25395; }","duration":"481.477853ms","start":"2026-07-24T00:38:00.784208Z","end":"2026-07-24T00:38:01.265686Z","steps":["trace[109047822] 'read index received' (duration: 128.683847ms)","trace[109047822] 'applied index is now lower than readState.Index' (duration: 352.792406ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:01.265837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:00.782798Z","time spent":"482.987864ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:38:01.265924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.237479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:01.265979Z","caller":"traceutil/trace.go:171","msg":"trace[1469279074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24593; }","duration":"450.32644ms","start":"2026-07-24T00:38:00.815644Z","end":"2026-07-24T00:38:01.26597Z","steps":["trace[1469279074] 'agreement among raft nodes before linearized reading' (duration: 450.250938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.266022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:00.815618Z","time spent":"450.397712ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T00:38:01.265941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.741869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-38b95e25-3d62-442e-aa92-69542e98964d\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-07-24T00:38:01.266089Z","caller":"traceutil/trace.go:171","msg":"trace[1929575408] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-38b95e25-3d62-442e-aa92-69542e98964d; range_end:; response_count:1; response_revision:24593; }","duration":"481.945873ms","start":"2026-07-24T00:38:00.784133Z","end":"2026-07-24T00:38:01.266079Z","steps":["trace[1929575408] 'agreement among raft nodes before linearized reading' (duration: 481.695668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.2675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:00.784104Z","time spent":"483.385232ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-38b95e25-3d62-442e-aa92-69542e98964d\" "} {"level":"warn","ts":"2026-07-24T00:38:01.761115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.204192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:38:01.761307Z","caller":"traceutil/trace.go:171","msg":"trace[202822423] linearizableReadLoop","detail":"{readStateIndex:25401; appliedIndex:25398; }","duration":"384.586152ms","start":"2026-07-24T00:38:01.376708Z","end":"2026-07-24T00:38:01.761294Z","steps":["trace[202822423] 'read index received' (duration: 97.089244ms)","trace[202822423] 'applied index is now lower than readState.Index' (duration: 287.496068ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:01.76132Z","caller":"traceutil/trace.go:171","msg":"trace[1612301643] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"406.467288ms","start":"2026-07-24T00:38:01.354821Z","end":"2026-07-24T00:38:01.761288Z","steps":["trace[1612301643] 'process raft request' (duration: 118.99098ms)","trace[1612301643] 'compare' (duration: 286.905496ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:01.761471Z","caller":"traceutil/trace.go:171","msg":"trace[593796696] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"401.392234ms","start":"2026-07-24T00:38:01.360072Z","end":"2026-07-24T00:38:01.761464Z","steps":["trace[593796696] 'process raft request' (duration: 401.135539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.761489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:01.354785Z","time spent":"406.60808ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:38:01.761515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:01.360037Z","time spent":"401.457245ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:38:01.761739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.320056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T00:38:01.761779Z","caller":"traceutil/trace.go:171","msg":"trace[1346191802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24596; }","duration":"382.368217ms","start":"2026-07-24T00:38:01.3794Z","end":"2026-07-24T00:38:01.761769Z","steps":["trace[1346191802] 'agreement among raft nodes before linearized reading' (duration: 382.228884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.761814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:01.379391Z","time spent":"382.416628ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T00:38:01.761832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.681012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:01.761855Z","caller":"traceutil/trace.go:171","msg":"trace[2142926150] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24596; }","duration":"348.744553ms","start":"2026-07-24T00:38:01.413105Z","end":"2026-07-24T00:38:01.761849Z","steps":["trace[2142926150] 'agreement among raft nodes before linearized reading' (duration: 348.690272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.761874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:01.413077Z","time spent":"348.793724ms","remote":"127.0.0.1:40028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-24T00:38:01.761964Z","caller":"traceutil/trace.go:171","msg":"trace[259178839] transaction","detail":"{read_only:false; response_revision:24596; number_of_response:1; }","duration":"397.7221ms","start":"2026-07-24T00:38:01.364236Z","end":"2026-07-24T00:38:01.761959Z","steps":["trace[259178839] 'process raft request' (duration: 397.036127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.762029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.794914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T00:38:01.76207Z","caller":"traceutil/trace.go:171","msg":"trace[1605278125] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24596; }","duration":"375.846304ms","start":"2026-07-24T00:38:01.386212Z","end":"2026-07-24T00:38:01.762059Z","steps":["trace[1605278125] 'agreement among raft nodes before linearized reading' (duration: 375.754453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.761996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:01.364223Z","time spent":"397.756601ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:38:01.762097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:01.386205Z","time spent":"375.886845ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T00:38:01.762226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.5569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T00:38:01.762257Z","caller":"traceutil/trace.go:171","msg":"trace[1738868588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24596; }","duration":"378.60291ms","start":"2026-07-24T00:38:01.383647Z","end":"2026-07-24T00:38:01.76225Z","steps":["trace[1738868588] 'agreement among raft nodes before linearized reading' (duration: 378.510948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.762289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:01.383641Z","time spent":"378.642422ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T00:38:01.762424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.252744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-24T00:38:01.762449Z","caller":"traceutil/trace.go:171","msg":"trace[1328067692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24596; }","duration":"379.382796ms","start":"2026-07-24T00:38:01.38306Z","end":"2026-07-24T00:38:01.762443Z","steps":["trace[1328067692] 'agreement among raft nodes before linearized reading' (duration: 379.331785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.762468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:01.383023Z","time spent":"379.441877ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T00:38:01.762265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.555551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-24T00:38:01.762539Z","caller":"traceutil/trace.go:171","msg":"trace[1218919738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24596; }","duration":"385.864238ms","start":"2026-07-24T00:38:01.37667Z","end":"2026-07-24T00:38:01.762534Z","steps":["trace[1218919738] 'agreement among raft nodes before linearized reading' (duration: 385.513511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:01.762553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:01.376651Z","time spent":"385.898258ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T00:38:02.051898Z","caller":"traceutil/trace.go:171","msg":"trace[1246467478] linearizableReadLoop","detail":"{readStateIndex:25402; appliedIndex:25401; }","duration":"282.69043ms","start":"2026-07-24T00:38:01.769174Z","end":"2026-07-24T00:38:02.051865Z","steps":["trace[1246467478] 'read index received' (duration: 276.117645ms)","trace[1246467478] 'applied index is now lower than readState.Index' (duration: 6.571115ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:02.051925Z","caller":"traceutil/trace.go:171","msg":"trace[1148152678] transaction","detail":"{read_only:false; response_revision:24597; number_of_response:1; }","duration":"284.726902ms","start":"2026-07-24T00:38:01.767159Z","end":"2026-07-24T00:38:02.051886Z","steps":["trace[1148152678] 'process raft request' (duration: 278.143128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:02.05233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.12934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-24T00:38:02.052326Z","caller":"traceutil/trace.go:171","msg":"trace[384924195] transaction","detail":"{read_only:false; response_revision:24601; number_of_response:1; }","duration":"282.545437ms","start":"2026-07-24T00:38:01.76975Z","end":"2026-07-24T00:38:02.052296Z","steps":["trace[384924195] 'process raft request' (duration: 282.489176ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:02.052393Z","caller":"traceutil/trace.go:171","msg":"trace[1371672369] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24598; }","duration":"283.240311ms","start":"2026-07-24T00:38:01.769134Z","end":"2026-07-24T00:38:02.052374Z","steps":["trace[1371672369] 'agreement among raft nodes before linearized reading' (duration: 282.817063ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:02.05241Z","caller":"traceutil/trace.go:171","msg":"trace[2101272412] transaction","detail":"{read_only:false; response_revision:24600; number_of_response:1; }","duration":"282.67062ms","start":"2026-07-24T00:38:01.769711Z","end":"2026-07-24T00:38:02.052382Z","steps":["trace[2101272412] 'process raft request' (duration: 282.485586ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:02.052301Z","caller":"traceutil/trace.go:171","msg":"trace[153958969] transaction","detail":"{read_only:false; response_revision:24598; number_of_response:1; }","duration":"282.65869ms","start":"2026-07-24T00:38:01.769599Z","end":"2026-07-24T00:38:02.052258Z","steps":["trace[153958969] 'process raft request' (duration: 282.211271ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:02.053034Z","caller":"traceutil/trace.go:171","msg":"trace[1707817431] transaction","detail":"{read_only:false; response_revision:24599; number_of_response:1; }","duration":"282.957075ms","start":"2026-07-24T00:38:01.769679Z","end":"2026-07-24T00:38:02.052636Z","steps":["trace[1707817431] 'process raft request' (duration: 282.426085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:07.246084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.170748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-24T00:38:07.24619Z","caller":"traceutil/trace.go:171","msg":"trace[1906906595] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24654; }","duration":"171.329492ms","start":"2026-07-24T00:38:07.074837Z","end":"2026-07-24T00:38:07.246167Z","steps":["trace[1906906595] 'range keys from in-memory index tree' (duration: 170.926464ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:07.42406Z","caller":"traceutil/trace.go:171","msg":"trace[833561342] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"140.200729ms","start":"2026-07-24T00:38:07.28383Z","end":"2026-07-24T00:38:07.424031Z","steps":["trace[833561342] 'process raft request' (duration: 139.946264ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:07.516932Z","caller":"traceutil/trace.go:171","msg":"trace[695464375] linearizableReadLoop","detail":"{readStateIndex:25464; appliedIndex:25461; }","duration":"147.192892ms","start":"2026-07-24T00:38:07.369707Z","end":"2026-07-24T00:38:07.5169Z","steps":["trace[695464375] 'read index received' (duration: 54.1395ms)","trace[695464375] 'applied index is now lower than readState.Index' (duration: 93.051752ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:07.517032Z","caller":"traceutil/trace.go:171","msg":"trace[1585593530] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"228.819081ms","start":"2026-07-24T00:38:07.288203Z","end":"2026-07-24T00:38:07.517022Z","steps":["trace[1585593530] 'process raft request' (duration: 228.627976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:07.517213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.468948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:07.51694Z","caller":"traceutil/trace.go:171","msg":"trace[746766725] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"230.325831ms","start":"2026-07-24T00:38:07.286575Z","end":"2026-07-24T00:38:07.516901Z","steps":["trace[746766725] 'process raft request' (duration: 229.651587ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:07.517336Z","caller":"traceutil/trace.go:171","msg":"trace[1345127335] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24657; }","duration":"147.651111ms","start":"2026-07-24T00:38:07.369657Z","end":"2026-07-24T00:38:07.517308Z","steps":["trace[1345127335] 'agreement among raft nodes before linearized reading' (duration: 147.429607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:24.146914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.091065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:38:24.147078Z","caller":"traceutil/trace.go:171","msg":"trace[640596666] linearizableReadLoop","detail":"{readStateIndex:25601; appliedIndex:25599; }","duration":"269.67712ms","start":"2026-07-24T00:38:23.877386Z","end":"2026-07-24T00:38:24.147063Z","steps":["trace[640596666] 'read index received' (duration: 8.015113ms)","trace[640596666] 'applied index is now lower than readState.Index' (duration: 261.661247ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:24.147174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.786632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:24.147193Z","caller":"traceutil/trace.go:171","msg":"trace[1687234844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24791; }","duration":"269.849303ms","start":"2026-07-24T00:38:23.877336Z","end":"2026-07-24T00:38:24.147185Z","steps":["trace[1687234844] 'agreement among raft nodes before linearized reading' (duration: 269.784342ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:24.147164Z","caller":"traceutil/trace.go:171","msg":"trace[1905656822] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"273.502598ms","start":"2026-07-24T00:38:23.873627Z","end":"2026-07-24T00:38:24.147129Z","steps":["trace[1905656822] 'process raft request' (duration: 140.108387ms)","trace[1905656822] 'compare' (duration: 132.933812ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:24.147196Z","caller":"traceutil/trace.go:171","msg":"trace[772402320] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"264.212799ms","start":"2026-07-24T00:38:23.88295Z","end":"2026-07-24T00:38:24.147162Z","steps":["trace[772402320] 'process raft request' (duration: 264.065896ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:24.482906Z","caller":"traceutil/trace.go:171","msg":"trace[732739050] linearizableReadLoop","detail":"{readStateIndex:25603; appliedIndex:25602; }","duration":"293.118838ms","start":"2026-07-24T00:38:24.189756Z","end":"2026-07-24T00:38:24.482875Z","steps":["trace[732739050] 'read index received' (duration: 292.865943ms)","trace[732739050] 'applied index is now lower than readState.Index' (duration: 251.495µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:24.483Z","caller":"traceutil/trace.go:171","msg":"trace[709219722] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"297.356743ms","start":"2026-07-24T00:38:24.185606Z","end":"2026-07-24T00:38:24.482963Z","steps":["trace[709219722] 'process raft request' (duration: 296.986506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:24.483183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.401833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-24T00:38:24.483208Z","caller":"traceutil/trace.go:171","msg":"trace[628814508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24792; }","duration":"293.500575ms","start":"2026-07-24T00:38:24.189701Z","end":"2026-07-24T00:38:24.483201Z","steps":["trace[628814508] 'agreement among raft nodes before linearized reading' (duration: 293.322642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:24.483248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.021575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-24T00:38:24.483326Z","caller":"traceutil/trace.go:171","msg":"trace[1773036271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24792; }","duration":"293.144728ms","start":"2026-07-24T00:38:24.190164Z","end":"2026-07-24T00:38:24.483308Z","steps":["trace[1773036271] 'agreement among raft nodes before linearized reading' (duration: 292.911284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:24.483438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.567711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T00:38:24.483478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.426303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-24T00:38:24.483505Z","caller":"traceutil/trace.go:171","msg":"trace[449338053] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24792; }","duration":"292.464433ms","start":"2026-07-24T00:38:24.19103Z","end":"2026-07-24T00:38:24.483495Z","steps":["trace[449338053] 'agreement among raft nodes before linearized reading' (duration: 292.397132ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:24.483508Z","caller":"traceutil/trace.go:171","msg":"trace[1361133537] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24792; }","duration":"246.720614ms","start":"2026-07-24T00:38:24.236772Z","end":"2026-07-24T00:38:24.483493Z","steps":["trace[1361133537] 'agreement among raft nodes before linearized reading' (duration: 246.567771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:24.483621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.3438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-24T00:38:24.483744Z","caller":"traceutil/trace.go:171","msg":"trace[1263011147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24792; }","duration":"288.495602ms","start":"2026-07-24T00:38:24.19522Z","end":"2026-07-24T00:38:24.483715Z","steps":["trace[1263011147] 'agreement among raft nodes before linearized reading' (duration: 288.151606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:24.483754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.920581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-24T00:38:24.483799Z","caller":"traceutil/trace.go:171","msg":"trace[1634316469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24792; }","duration":"162.019223ms","start":"2026-07-24T00:38:24.321769Z","end":"2026-07-24T00:38:24.483788Z","steps":["trace[1634316469] 'agreement among raft nodes before linearized reading' (duration: 161.843309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:24.749975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.933695ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-24T00:38:24.771961Z","caller":"traceutil/trace.go:171","msg":"trace[337184521] transaction","detail":"{read_only:false; response_revision:24793; number_of_response:1; }","duration":"284.500512ms","start":"2026-07-24T00:38:24.487434Z","end":"2026-07-24T00:38:24.771935Z","steps":["trace[337184521] 'process raft request' (duration: 284.256228ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:24.772267Z","caller":"traceutil/trace.go:171","msg":"trace[1881425113] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"282.823878ms","start":"2026-07-24T00:38:24.489411Z","end":"2026-07-24T00:38:24.772234Z","steps":["trace[1881425113] 'process raft request' (duration: 282.44161ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:24.772322Z","caller":"traceutil/trace.go:171","msg":"trace[561527009] transaction","detail":"{read_only:false; response_revision:24795; number_of_response:1; }","duration":"282.887189ms","start":"2026-07-24T00:38:24.489418Z","end":"2026-07-24T00:38:24.772305Z","steps":["trace[561527009] 'process raft request' (duration: 282.717796ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:24.772581Z","caller":"traceutil/trace.go:171","msg":"trace[1768857924] transaction","detail":"{read_only:false; response_revision:24797; number_of_response:1; }","duration":"282.853189ms","start":"2026-07-24T00:38:24.489689Z","end":"2026-07-24T00:38:24.772542Z","steps":["trace[1768857924] 'process raft request' (duration: 282.723666ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:24.77282Z","caller":"traceutil/trace.go:171","msg":"trace[2065811642] transaction","detail":"{read_only:false; response_revision:24796; number_of_response:1; }","duration":"283.322408ms","start":"2026-07-24T00:38:24.489486Z","end":"2026-07-24T00:38:24.772809Z","steps":["trace[2065811642] 'process raft request' (duration: 282.774277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:25.032394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.450326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:38:25.03252Z","caller":"traceutil/trace.go:171","msg":"trace[1182085700] transaction","detail":"{read_only:false; response_revision:24798; number_of_response:1; }","duration":"281.117553ms","start":"2026-07-24T00:38:24.751368Z","end":"2026-07-24T00:38:25.032486Z","steps":["trace[1182085700] 'process raft request' (duration: 138.474564ms)","trace[1182085700] 'compare' (duration: 142.119118ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:25.617266Z","caller":"traceutil/trace.go:171","msg":"trace[862919117] transaction","detail":"{read_only:false; response_revision:24800; number_of_response:1; }","duration":"124.42684ms","start":"2026-07-24T00:38:25.492814Z","end":"2026-07-24T00:38:25.61724Z","steps":["trace[862919117] 'process raft request' (duration: 124.230726ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:25.649039Z","caller":"traceutil/trace.go:171","msg":"trace[1375181763] transaction","detail":"{read_only:false; response_revision:24801; number_of_response:1; }","duration":"127.792147ms","start":"2026-07-24T00:38:25.521207Z","end":"2026-07-24T00:38:25.648999Z","steps":["trace[1375181763] 'process raft request' (duration: 127.055253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:26.010074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.495564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:26.010163Z","caller":"traceutil/trace.go:171","msg":"trace[1527551397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24803; }","duration":"197.638897ms","start":"2026-07-24T00:38:25.812503Z","end":"2026-07-24T00:38:26.010142Z","steps":["trace[1527551397] 'range keys from in-memory index tree' (duration: 197.405541ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:26.010744Z","caller":"traceutil/trace.go:171","msg":"trace[1026699513] transaction","detail":"{read_only:false; response_revision:24804; number_of_response:1; }","duration":"117.853495ms","start":"2026-07-24T00:38:25.892868Z","end":"2026-07-24T00:38:26.010722Z","steps":["trace[1026699513] 'process raft request' (duration: 42.553885ms)","trace[1026699513] 'compare' (duration: 74.919313ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:26.238928Z","caller":"traceutil/trace.go:171","msg":"trace[1710246223] linearizableReadLoop","detail":"{readStateIndex:25617; appliedIndex:25616; }","duration":"132.159237ms","start":"2026-07-24T00:38:26.106717Z","end":"2026-07-24T00:38:26.238876Z","steps":["trace[1710246223] 'read index received' (duration: 103.592645ms)","trace[1710246223] 'applied index is now lower than readState.Index' (duration: 28.564451ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:26.238973Z","caller":"traceutil/trace.go:171","msg":"trace[864274427] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"217.196645ms","start":"2026-07-24T00:38:26.021731Z","end":"2026-07-24T00:38:26.238928Z","steps":["trace[864274427] 'process raft request' (duration: 188.564402ms)","trace[864274427] 'compare' (duration: 28.398688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:26.239325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.579645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:26.239432Z","caller":"traceutil/trace.go:171","msg":"trace[640743842] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24805; }","duration":"132.713397ms","start":"2026-07-24T00:38:26.106699Z","end":"2026-07-24T00:38:26.239413Z","steps":["trace[640743842] 'agreement among raft nodes before linearized reading' (duration: 132.307429ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:26.500075Z","caller":"traceutil/trace.go:171","msg":"trace[96453475] transaction","detail":"{read_only:false; response_revision:24806; number_of_response:1; }","duration":"347.633665ms","start":"2026-07-24T00:38:26.152416Z","end":"2026-07-24T00:38:26.500049Z","steps":["trace[96453475] 'process raft request' (duration: 336.241774ms)","trace[96453475] 'compare' (duration: 10.629226ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:26.500242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:26.152388Z","time spent":"347.768237ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:38:26.499961Z","caller":"traceutil/trace.go:171","msg":"trace[1407814657] transaction","detail":"{read_only:false; response_revision:24807; number_of_response:1; }","duration":"346.988762ms","start":"2026-07-24T00:38:26.152936Z","end":"2026-07-24T00:38:26.499925Z","steps":["trace[1407814657] 'process raft request' (duration: 346.834479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:26.500349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:26.152904Z","time spent":"347.39486ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:38:26.676088Z","caller":"traceutil/trace.go:171","msg":"trace[286389756] linearizableReadLoop","detail":"{readStateIndex:25623; appliedIndex:25617; }","duration":"352.142227ms","start":"2026-07-24T00:38:26.323912Z","end":"2026-07-24T00:38:26.676054Z","steps":["trace[286389756] 'read index received' (duration: 164.739918ms)","trace[286389756] 'applied index is now lower than readState.Index' (duration: 187.400789ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:26.676422Z","caller":"traceutil/trace.go:171","msg":"trace[812149949] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"422.353604ms","start":"2026-07-24T00:38:26.254034Z","end":"2026-07-24T00:38:26.676388Z","steps":["trace[812149949] 'process raft request' (duration: 421.948736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:26.676464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.537714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:26.676537Z","caller":"traceutil/trace.go:171","msg":"trace[1967387301] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:24811; }","duration":"352.632246ms","start":"2026-07-24T00:38:26.323888Z","end":"2026-07-24T00:38:26.67652Z","steps":["trace[1967387301] 'agreement among raft nodes before linearized reading' (duration: 352.456053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:26.676578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:26.323815Z","time spent":"352.752969ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-07-24T00:38:26.6766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:26.254026Z","time spent":"422.495146ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9630,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:38:26.676388Z","caller":"traceutil/trace.go:171","msg":"trace[368041816] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"429.716264ms","start":"2026-07-24T00:38:26.246618Z","end":"2026-07-24T00:38:26.676335Z","steps":["trace[368041816] 'process raft request' (duration: 429.309735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:26.676903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:26.246592Z","time spent":"430.245414ms","remote":"127.0.0.1:39842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:38:26.67693Z","caller":"traceutil/trace.go:171","msg":"trace[293903921] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"430.612621ms","start":"2026-07-24T00:38:26.246299Z","end":"2026-07-24T00:38:26.676912Z","steps":["trace[293903921] 'process raft request' (duration: 429.293345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:26.676908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.323397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-24T00:38:26.677088Z","caller":"traceutil/trace.go:171","msg":"trace[1282888474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24811; }","duration":"188.48494ms","start":"2026-07-24T00:38:26.488574Z","end":"2026-07-24T00:38:26.677059Z","steps":["trace[1282888474] 'agreement among raft nodes before linearized reading' (duration: 188.202054ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:26.677093Z","caller":"traceutil/trace.go:171","msg":"trace[2046819516] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"430.58811ms","start":"2026-07-24T00:38:26.246446Z","end":"2026-07-24T00:38:26.677034Z","steps":["trace[2046819516] 'process raft request' (duration: 429.405087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:26.677226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:26.246267Z","time spent":"430.717293ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:38:26.677342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:26.246432Z","time spent":"430.777595ms","remote":"127.0.0.1:39842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:38:30.614702Z","caller":"traceutil/trace.go:171","msg":"trace[576117362] transaction","detail":"{read_only:false; response_revision:24846; number_of_response:1; }","duration":"101.954092ms","start":"2026-07-24T00:38:30.512709Z","end":"2026-07-24T00:38:30.614664Z","steps":["trace[576117362] 'process raft request' (duration: 101.750108ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:30.68056Z","caller":"traceutil/trace.go:171","msg":"trace[1630324887] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"167.481563ms","start":"2026-07-24T00:38:30.513019Z","end":"2026-07-24T00:38:30.6805Z","steps":["trace[1630324887] 'process raft request' (duration: 155.428789ms)","trace[1630324887] 'compare' (duration: 11.633196ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:30.680705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.737277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:30.68074Z","caller":"traceutil/trace.go:171","msg":"trace[1061243487] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:24848; }","duration":"132.845289ms","start":"2026-07-24T00:38:30.547887Z","end":"2026-07-24T00:38:30.680732Z","steps":["trace[1061243487] 'agreement among raft nodes before linearized reading' (duration: 132.754447ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:30.680602Z","caller":"traceutil/trace.go:171","msg":"trace[951879449] linearizableReadLoop","detail":"{readStateIndex:25660; appliedIndex:25658; }","duration":"132.631635ms","start":"2026-07-24T00:38:30.547949Z","end":"2026-07-24T00:38:30.68058Z","steps":["trace[951879449] 'read index received' (duration: 66.528433ms)","trace[951879449] 'applied index is now lower than readState.Index' (duration: 66.101792ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:31.022643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.711811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T00:38:31.022725Z","caller":"traceutil/trace.go:171","msg":"trace[624314867] linearizableReadLoop","detail":"{readStateIndex:25662; appliedIndex:25661; }","duration":"289.176377ms","start":"2026-07-24T00:38:30.733536Z","end":"2026-07-24T00:38:31.022712Z","steps":["trace[624314867] 'read index received' (duration: 111.227111ms)","trace[624314867] 'applied index is now lower than readState.Index' (duration: 177.948126ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:31.022891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.32831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T00:38:31.022975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.351184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T00:38:31.022979Z","caller":"traceutil/trace.go:171","msg":"trace[1770049362] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24849; }","duration":"289.471203ms","start":"2026-07-24T00:38:30.733487Z","end":"2026-07-24T00:38:31.022958Z","steps":["trace[1770049362] 'agreement among raft nodes before linearized reading' (duration: 289.303389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:31.02319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.038769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T00:38:31.023215Z","caller":"traceutil/trace.go:171","msg":"trace[1629822389] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24849; }","duration":"151.112411ms","start":"2026-07-24T00:38:30.872098Z","end":"2026-07-24T00:38:31.02321Z","steps":["trace[1629822389] 'agreement among raft nodes before linearized reading' (duration: 151.061679ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:31.023268Z","caller":"traceutil/trace.go:171","msg":"trace[910183998] transaction","detail":"{read_only:false; response_revision:24849; number_of_response:1; }","duration":"312.551973ms","start":"2026-07-24T00:38:30.7107Z","end":"2026-07-24T00:38:31.023252Z","steps":["trace[910183998] 'process raft request' (duration: 134.151827ms)","trace[910183998] 'compare' (duration: 177.242382ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:31.023344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.466486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-24T00:38:31.023364Z","caller":"traceutil/trace.go:171","msg":"trace[1851248419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24849; }","duration":"206.486087ms","start":"2026-07-24T00:38:30.816873Z","end":"2026-07-24T00:38:31.023359Z","steps":["trace[1851248419] 'agreement among raft nodes before linearized reading' (duration: 206.436016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:31.023389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:30.71067Z","time spent":"312.652764ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:38:31.02346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.789803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-24T00:38:31.023479Z","caller":"traceutil/trace.go:171","msg":"trace[2095073852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24849; }","duration":"206.818383ms","start":"2026-07-24T00:38:30.816657Z","end":"2026-07-24T00:38:31.023475Z","steps":["trace[2095073852] 'agreement among raft nodes before linearized reading' (duration: 206.776002ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:31.022998Z","caller":"traceutil/trace.go:171","msg":"trace[303777812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24849; }","duration":"209.407845ms","start":"2026-07-24T00:38:30.813579Z","end":"2026-07-24T00:38:31.022987Z","steps":["trace[303777812] 'agreement among raft nodes before linearized reading' (duration: 209.308843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:31.023301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.228312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T00:38:31.023792Z","caller":"traceutil/trace.go:171","msg":"trace[720447376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24849; }","duration":"206.713341ms","start":"2026-07-24T00:38:30.817066Z","end":"2026-07-24T00:38:31.02378Z","steps":["trace[720447376] 'agreement among raft nodes before linearized reading' (duration: 206.206971ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:31.347664Z","caller":"traceutil/trace.go:171","msg":"trace[1900785827] transaction","detail":"{read_only:false; response_revision:24851; number_of_response:1; }","duration":"319.503793ms","start":"2026-07-24T00:38:31.02813Z","end":"2026-07-24T00:38:31.347634Z","steps":["trace[1900785827] 'process raft request' (duration: 319.131366ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:31.347783Z","caller":"traceutil/trace.go:171","msg":"trace[1407729990] transaction","detail":"{read_only:false; response_revision:24852; number_of_response:1; }","duration":"318.667135ms","start":"2026-07-24T00:38:31.029089Z","end":"2026-07-24T00:38:31.347756Z","steps":["trace[1407729990] 'process raft request' (duration: 318.337399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:31.34783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:31.028105Z","time spent":"319.631555ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T00:38:31.347957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:31.029058Z","time spent":"318.806208ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:38:31.348253Z","caller":"traceutil/trace.go:171","msg":"trace[1524218186] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"317.482912ms","start":"2026-07-24T00:38:31.030758Z","end":"2026-07-24T00:38:31.348241Z","steps":["trace[1524218186] 'process raft request' (duration: 316.716376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:31.348338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:31.030744Z","time spent":"317.553464ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:38:31.348519Z","caller":"traceutil/trace.go:171","msg":"trace[1356935987] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"277.960789ms","start":"2026-07-24T00:38:31.070536Z","end":"2026-07-24T00:38:31.348497Z","steps":["trace[1356935987] 'process raft request' (duration: 277.869567ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:31.348688Z","caller":"traceutil/trace.go:171","msg":"trace[922168028] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"317.792309ms","start":"2026-07-24T00:38:31.030875Z","end":"2026-07-24T00:38:31.348667Z","steps":["trace[922168028] 'process raft request' (duration: 317.293359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:31.348765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T00:38:31.030844Z","time spent":"317.86179ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T00:38:34.874091Z","caller":"traceutil/trace.go:171","msg":"trace[324796520] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"168.593036ms","start":"2026-07-24T00:38:34.705472Z","end":"2026-07-24T00:38:34.874065Z","steps":["trace[324796520] 'process raft request' (duration: 168.374662ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:34.888215Z","caller":"traceutil/trace.go:171","msg":"trace[1530511503] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"165.189327ms","start":"2026-07-24T00:38:34.723007Z","end":"2026-07-24T00:38:34.888196Z","steps":["trace[1530511503] 'process raft request' (duration: 165.081485ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:47.27726Z","caller":"traceutil/trace.go:171","msg":"trace[1084531097] linearizableReadLoop","detail":"{readStateIndex:25794; appliedIndex:25793; }","duration":"100.925171ms","start":"2026-07-24T00:38:47.176309Z","end":"2026-07-24T00:38:47.277234Z","steps":["trace[1084531097] 'read index received' (duration: 100.765127ms)","trace[1084531097] 'applied index is now lower than readState.Index' (duration: 158.894µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:47.277337Z","caller":"traceutil/trace.go:171","msg":"trace[1072317666] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"103.136067ms","start":"2026-07-24T00:38:47.174144Z","end":"2026-07-24T00:38:47.27728Z","steps":["trace[1072317666] 'process raft request' (duration: 102.891302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:47.277464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.125325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:47.277531Z","caller":"traceutil/trace.go:171","msg":"trace[87211528] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24978; }","duration":"101.273988ms","start":"2026-07-24T00:38:47.176237Z","end":"2026-07-24T00:38:47.277511Z","steps":["trace[87211528] 'agreement among raft nodes before linearized reading' (duration: 101.130245ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:51.15645Z","caller":"traceutil/trace.go:171","msg":"trace[337186573] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"137.805731ms","start":"2026-07-24T00:38:51.018615Z","end":"2026-07-24T00:38:51.156421Z","steps":["trace[337186573] 'process raft request' (duration: 137.641327ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:51.236658Z","caller":"traceutil/trace.go:171","msg":"trace[219814193] linearizableReadLoop","detail":"{readStateIndex:25823; appliedIndex:25821; }","duration":"176.442856ms","start":"2026-07-24T00:38:51.060191Z","end":"2026-07-24T00:38:51.236634Z","steps":["trace[219814193] 'read index received' (duration: 96.089583ms)","trace[219814193] 'applied index is now lower than readState.Index' (duration: 80.352083ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:51.236717Z","caller":"traceutil/trace.go:171","msg":"trace[1340070686] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"201.168899ms","start":"2026-07-24T00:38:51.035509Z","end":"2026-07-24T00:38:51.236678Z","steps":["trace[1340070686] 'process raft request' (duration: 154.885449ms)","trace[1340070686] 'compare' (duration: 46.027155ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T00:38:51.236813Z","caller":"traceutil/trace.go:171","msg":"trace[67151556] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"160.975932ms","start":"2026-07-24T00:38:51.075821Z","end":"2026-07-24T00:38:51.236797Z","steps":["trace[67151556] 'process raft request' (duration: 160.760697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:51.236931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.436666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-24T00:38:51.236988Z","caller":"traceutil/trace.go:171","msg":"trace[444710531] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25007; }","duration":"176.505957ms","start":"2026-07-24T00:38:51.060469Z","end":"2026-07-24T00:38:51.236975Z","steps":["trace[444710531] 'agreement among raft nodes before linearized reading' (duration: 176.350754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T00:38:51.237025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.810633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:51.237073Z","caller":"traceutil/trace.go:171","msg":"trace[299725199] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25007; }","duration":"176.925276ms","start":"2026-07-24T00:38:51.060136Z","end":"2026-07-24T00:38:51.237061Z","steps":["trace[299725199] 'agreement among raft nodes before linearized reading' (duration: 176.833394ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:56.915446Z","caller":"traceutil/trace.go:171","msg":"trace[716745156] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"257.051754ms","start":"2026-07-24T00:38:56.658371Z","end":"2026-07-24T00:38:56.915423Z","steps":["trace[716745156] 'process raft request' (duration: 256.790058ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:56.915507Z","caller":"traceutil/trace.go:171","msg":"trace[75182240] linearizableReadLoop","detail":"{readStateIndex:25867; appliedIndex:25866; }","duration":"100.704537ms","start":"2026-07-24T00:38:56.814763Z","end":"2026-07-24T00:38:56.915468Z","steps":["trace[75182240] 'read index received' (duration: 100.37885ms)","trace[75182240] 'applied index is now lower than readState.Index' (duration: 321.947µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T00:38:56.915651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.864429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T00:38:56.915729Z","caller":"traceutil/trace.go:171","msg":"trace[1958756259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25049; }","duration":"101.005642ms","start":"2026-07-24T00:38:56.814704Z","end":"2026-07-24T00:38:56.91571Z","steps":["trace[1958756259] 'agreement among raft nodes before linearized reading' (duration: 100.842659ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:38:57.418982Z","caller":"traceutil/trace.go:171","msg":"trace[700070572] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"163.895652ms","start":"2026-07-24T00:38:57.255051Z","end":"2026-07-24T00:38:57.418947Z","steps":["trace[700070572] 'process raft request' (duration: 163.524034ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T00:40:00.127689Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000679e.wal"} {"level":"info","ts":"2026-07-24T00:40:41.231743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23248} {"level":"info","ts":"2026-07-24T00:40:41.375039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23248,"took":"139.035336ms","hash":2510702768,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":36552704,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-24T00:40:41.375161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2510702768,"revision":23248,"compact-revision":19995} {"level":"info","ts":"2026-07-24T00:45:41.239226Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26109} {"level":"info","ts":"2026-07-24T00:45:41.387377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26109,"took":"143.917339ms","hash":1586481044,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":34025472,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-24T00:45:41.387453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1586481044,"revision":26109,"compact-revision":23248} {"level":"info","ts":"2026-07-24T00:46:30.988176Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T00:46:30.992757Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-24T00:46:30.992926Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-24T00:50:41.27094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28708} {"level":"info","ts":"2026-07-24T00:50:41.442956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28708,"took":"166.134056ms","hash":1931750956,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":30879744,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T00:50:41.443048Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1931750956,"revision":28708,"compact-revision":26109} {"level":"info","ts":"2026-07-24T00:55:41.277321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30929} {"level":"info","ts":"2026-07-24T00:55:41.40246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30929,"took":"119.120849ms","hash":2258648284,"current-db-size-bytes":58417152,"current-db-size":"58 MB","current-db-size-in-use-bytes":31940608,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T00:55:41.402632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2258648284,"revision":30929,"compact-revision":28708} {"level":"info","ts":"2026-07-24T01:00:41.283719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33193} {"level":"info","ts":"2026-07-24T01:00:41.467009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33193,"took":"177.86662ms","hash":2267372169,"current-db-size-bytes":59457536,"current-db-size":"60 MB","current-db-size-in-use-bytes":32333824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T01:00:41.467102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2267372169,"revision":33193,"compact-revision":30929} {"level":"info","ts":"2026-07-24T01:05:41.305946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35556} {"level":"info","ts":"2026-07-24T01:05:41.453939Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35556,"took":"143.720907ms","hash":982734356,"current-db-size-bytes":61214720,"current-db-size":"61 MB","current-db-size-in-use-bytes":31973376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T01:05:41.454035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":982734356,"revision":35556,"compact-revision":33193} {"level":"info","ts":"2026-07-24T01:07:49.565376Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T01:07:49.570178Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-24T01:07:49.57034Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-24T01:10:41.313371Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37813} {"level":"info","ts":"2026-07-24T01:10:41.437703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37813,"took":"119.249151ms","hash":3511352857,"current-db-size-bytes":61214720,"current-db-size":"61 MB","current-db-size-in-use-bytes":32464896,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T01:10:41.437772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3511352857,"revision":37813,"compact-revision":35556}