{"level":"warn","ts":"2026-05-21T02:56:32.751068Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T02:56:32.751153Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-21T02:56:32.751215Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T02:56:32.751228Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-21T02:56:32.751244Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T02:56:32.751995Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-05-21T02:56:32.752062Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-21T02:56:33.596298Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"772.00038ms"} {"level":"info","ts":"2026-05-21T02:56:34.172828Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-05-21T02:56:34.172973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T02:56:34.173006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-05-21T02:56:34.173023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T02:56:34.173043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-05-21T02:56:34.173094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-05-21T02:56:34.174471Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T02:56:34.175912Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T02:56:34.177303Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-21T02:56:34.179491Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T02:56:34.179633Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T02:56:34.179769Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T02:56:34.179808Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T02:56:34.179819Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T02:56:34.180634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-05-21T02:56:34.180817Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-21T02:56:34.183069Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T02:56:34.183245Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T02:56:34.183299Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T02:56:34.18329Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-21T02:56:34.183373Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-21T02:56:35.173627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T02:56:35.173696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T02:56:35.173731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-05-21T02:56:35.173751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-05-21T02:56:35.173763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-21T02:56:35.173779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-05-21T02:56:35.1738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-21T02:56:35.175Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:56:35.175859Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T02:56:35.175878Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T02:56:35.175826Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T02:56:35.176671Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T02:56:35.176805Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T02:56:35.177109Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:56:35.177279Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:56:35.177318Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:56:35.177953Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-05-21T02:56:35.181787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T02:56:58.629128Z","caller":"traceutil/trace.go:171","msg":"trace[1827748999] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"151.91409ms","start":"2026-05-21T02:56:58.477189Z","end":"2026-05-21T02:56:58.629103Z","steps":["trace[1827748999] 'process raft request' (duration: 103.23419ms)","trace[1827748999] 'compare' (duration: 48.522498ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:56:58.629385Z","caller":"traceutil/trace.go:171","msg":"trace[140476720] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"151.592817ms","start":"2026-05-21T02:56:58.477756Z","end":"2026-05-21T02:56:58.629348Z","steps":["trace[140476720] 'process raft request' (duration: 151.310854ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:08.419776Z","caller":"traceutil/trace.go:171","msg":"trace[74577844] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"297.163063ms","start":"2026-05-21T02:57:08.122589Z","end":"2026-05-21T02:57:08.419752Z","steps":["trace[74577844] 'read index received' (duration: 297.108072ms)","trace[74577844] 'applied index is now lower than readState.Index' (duration: 53.641µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:57:08.420034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.394905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-05-21T02:57:08.420153Z","caller":"traceutil/trace.go:171","msg":"trace[618762782] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:478; }","duration":"297.591386ms","start":"2026-05-21T02:57:08.122541Z","end":"2026-05-21T02:57:08.420132Z","steps":["trace[618762782] 'agreement among raft nodes before linearized reading' (duration: 297.302494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:08.419807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:08.107682Z","time spent":"312.117901ms","remote":"127.0.0.1:42064","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T02:57:08.579468Z","caller":"traceutil/trace.go:171","msg":"trace[807885293] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"157.801586ms","start":"2026-05-21T02:57:08.421645Z","end":"2026-05-21T02:57:08.579447Z","steps":["trace[807885293] 'process raft request' (duration: 131.443431ms)","trace[807885293] 'compare' (duration: 26.184003ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:57:08.579783Z","caller":"traceutil/trace.go:171","msg":"trace[1108252885] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"153.929961ms","start":"2026-05-21T02:57:08.425841Z","end":"2026-05-21T02:57:08.579771Z","steps":["trace[1108252885] 'process raft request' (duration: 153.516377ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:11.431616Z","caller":"traceutil/trace.go:171","msg":"trace[1160208930] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"368.395804ms","start":"2026-05-21T02:57:11.063188Z","end":"2026-05-21T02:57:11.431584Z","steps":["trace[1160208930] 'process raft request' (duration: 367.92939ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:11.43164Z","caller":"traceutil/trace.go:171","msg":"trace[2017351300] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"368.108892ms","start":"2026-05-21T02:57:11.063487Z","end":"2026-05-21T02:57:11.431596Z","steps":["trace[2017351300] 'process raft request' (duration: 367.844149ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:11.431771Z","caller":"traceutil/trace.go:171","msg":"trace[848209847] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"368.147282ms","start":"2026-05-21T02:57:11.063614Z","end":"2026-05-21T02:57:11.431762Z","steps":["trace[848209847] 'process raft request' (duration: 367.88189ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:11.431824Z","caller":"traceutil/trace.go:171","msg":"trace[1130821500] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"368.230432ms","start":"2026-05-21T02:57:11.063568Z","end":"2026-05-21T02:57:11.431799Z","steps":["trace[1130821500] 'process raft request' (duration: 367.880229ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:11.43187Z","caller":"traceutil/trace.go:171","msg":"trace[1201222140] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"365.122375ms","start":"2026-05-21T02:57:11.066741Z","end":"2026-05-21T02:57:11.431863Z","steps":["trace[1201222140] 'process raft request' (duration: 364.916713ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:11.431902Z","caller":"traceutil/trace.go:171","msg":"trace[1669290364] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"365.200655ms","start":"2026-05-21T02:57:11.066694Z","end":"2026-05-21T02:57:11.431895Z","steps":["trace[1669290364] 'process raft request' (duration: 364.863912ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:11.432004Z","caller":"traceutil/trace.go:171","msg":"trace[2043435943] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"365.180025ms","start":"2026-05-21T02:57:11.066798Z","end":"2026-05-21T02:57:11.431978Z","steps":["trace[2043435943] 'process raft request' (duration: 364.927353ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:11.432008Z","caller":"traceutil/trace.go:171","msg":"trace[513084911] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"365.116285ms","start":"2026-05-21T02:57:11.066866Z","end":"2026-05-21T02:57:11.431982Z","steps":["trace[513084911] 'process raft request' (duration: 364.904773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.432147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.063171Z","time spent":"368.528885ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:57:11.432193Z","caller":"traceutil/trace.go:171","msg":"trace[1848595722] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"354.103146ms","start":"2026-05-21T02:57:11.078079Z","end":"2026-05-21T02:57:11.432183Z","steps":["trace[1848595722] 'process raft request' (duration: 354.012944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.432215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.06686Z","time spent":"365.211596ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:57:11.432257Z","caller":"traceutil/trace.go:171","msg":"trace[1975255338] transaction","detail":"{read_only:false; number_of_response:1; response_revision:534; }","duration":"365.155276ms","start":"2026-05-21T02:57:11.067042Z","end":"2026-05-21T02:57:11.432198Z","steps":["trace[1975255338] 'process raft request' (duration: 364.886412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.432293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.078064Z","time spent":"354.163516ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:57:11.432368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.066794Z","time spent":"365.266756ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:57:11.432411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.063481Z","time spent":"368.244433ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:57:11.432507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.067034Z","time spent":"365.305927ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:57:11.432553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.063563Z","time spent":"368.278103ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:57:11.432524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.063609Z","time spent":"368.181022ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:57:11.432644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.066676Z","time spent":"365.242665ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:57:11.432618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.066724Z","time spent":"365.163055ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:57:11.80531Z","caller":"traceutil/trace.go:171","msg":"trace[1093457715] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"724.116805ms","start":"2026-05-21T02:57:11.081158Z","end":"2026-05-21T02:57:11.805275Z","steps":["trace[1093457715] 'process raft request' (duration: 723.64852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.80544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.08114Z","time spent":"724.242156ms","remote":"127.0.0.1:42084","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-05-21T02:57:11.805441Z","caller":"traceutil/trace.go:171","msg":"trace[1632101585] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"723.507289ms","start":"2026-05-21T02:57:11.081896Z","end":"2026-05-21T02:57:11.805403Z","steps":["trace[1632101585] 'process raft request' (duration: 723.282587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.805599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.081888Z","time spent":"723.6363ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:57:11.805665Z","caller":"traceutil/trace.go:171","msg":"trace[226764199] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"718.009898ms","start":"2026-05-21T02:57:11.087644Z","end":"2026-05-21T02:57:11.805654Z","steps":["trace[226764199] 'process raft request' (duration: 717.687985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.805798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.087626Z","time spent":"718.147309ms","remote":"127.0.0.1:42544","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-05-21T02:57:11.805792Z","caller":"traceutil/trace.go:171","msg":"trace[915678316] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"714.026142ms","start":"2026-05-21T02:57:11.091738Z","end":"2026-05-21T02:57:11.805764Z","steps":["trace[915678316] 'process raft request' (duration: 713.640338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.805898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.091727Z","time spent":"714.111583ms","remote":"127.0.0.1:42084","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-05-21T02:57:11.806024Z","caller":"traceutil/trace.go:171","msg":"trace[297066052] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"570.862099ms","start":"2026-05-21T02:57:11.235149Z","end":"2026-05-21T02:57:11.806011Z","steps":["trace[297066052] 'process raft request' (duration: 570.656627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.806097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.23513Z","time spent":"570.94041ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:57:11.805987Z","caller":"traceutil/trace.go:171","msg":"trace[1272526142] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:542; }","duration":"495.586642ms","start":"2026-05-21T02:57:11.310344Z","end":"2026-05-21T02:57:11.80593Z","steps":["trace[1272526142] 'read index received' (duration: 121.010741ms)","trace[1272526142] 'applied index is now lower than readState.Index' (duration: 374.575121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:57:11.806169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.810284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:57:11.806256Z","caller":"traceutil/trace.go:171","msg":"trace[1118193652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"495.950976ms","start":"2026-05-21T02:57:11.310293Z","end":"2026-05-21T02:57:11.806244Z","steps":["trace[1118193652] 'agreement among raft nodes before linearized reading' (duration: 495.772315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.806323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.310274Z","time spent":"496.038337ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T02:57:11.806029Z","caller":"traceutil/trace.go:171","msg":"trace[757126447] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"695.670624ms","start":"2026-05-21T02:57:11.110334Z","end":"2026-05-21T02:57:11.806004Z","steps":["trace[757126447] 'process raft request' (duration: 695.27308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.807085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.110312Z","time spent":"696.151479ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:57:11.851673Z","caller":"traceutil/trace.go:171","msg":"trace[629729936] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"409.223996ms","start":"2026-05-21T02:57:11.442417Z","end":"2026-05-21T02:57:11.851641Z","steps":["trace[629729936] 'process raft request' (duration: 409.082525ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:11.8518Z","caller":"traceutil/trace.go:171","msg":"trace[1350701872] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"402.298922ms","start":"2026-05-21T02:57:11.449477Z","end":"2026-05-21T02:57:11.851776Z","steps":["trace[1350701872] 'process raft request' (duration: 402.267752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.851818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.442392Z","time spent":"409.358187ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:57:11.851908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.449463Z","time spent":"402.384863ms","remote":"127.0.0.1:42084","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-05-21T02:57:11.852152Z","caller":"traceutil/trace.go:171","msg":"trace[292316399] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"409.58451ms","start":"2026-05-21T02:57:11.442561Z","end":"2026-05-21T02:57:11.852145Z","steps":["trace[292316399] 'process raft request' (duration: 409.026965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.85218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.442541Z","time spent":"409.62315ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:57:11.85224Z","caller":"traceutil/trace.go:171","msg":"trace[377011936] transaction","detail":"{read_only:false; number_of_response:1; response_revision:543; }","duration":"409.568639ms","start":"2026-05-21T02:57:11.442666Z","end":"2026-05-21T02:57:11.852234Z","steps":["trace[377011936] 'process raft request' (duration: 408.962794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.852265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.442642Z","time spent":"409.60726ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:57:11.852283Z","caller":"traceutil/trace.go:171","msg":"trace[1848607832] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"404.08668ms","start":"2026-05-21T02:57:11.448183Z","end":"2026-05-21T02:57:11.85227Z","steps":["trace[1848607832] 'process raft request' (duration: 403.520865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.852361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.448177Z","time spent":"404.13676ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:57:11.852487Z","caller":"traceutil/trace.go:171","msg":"trace[1265044733] transaction","detail":"{read_only:false; number_of_response:1; response_revision:543; }","duration":"408.964154ms","start":"2026-05-21T02:57:11.443516Z","end":"2026-05-21T02:57:11.85248Z","steps":["trace[1265044733] 'process raft request' (duration: 408.138005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.852522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.443498Z","time spent":"409.007874ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7375,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:57:11.852488Z","caller":"traceutil/trace.go:171","msg":"trace[1901061833] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"406.874755ms","start":"2026-05-21T02:57:11.445594Z","end":"2026-05-21T02:57:11.852469Z","steps":["trace[1901061833] 'process raft request' (duration: 406.078967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:11.852685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:11.445588Z","time spent":"407.058727ms","remote":"127.0.0.1:42544","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-05-21T02:57:12.864809Z","caller":"traceutil/trace.go:171","msg":"trace[1957301948] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"208.729239ms","start":"2026-05-21T02:57:12.656054Z","end":"2026-05-21T02:57:12.864784Z","steps":["trace[1957301948] 'process raft request' (duration: 121.351099ms)","trace[1957301948] 'compare' (duration: 86.937986ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:57:12.865686Z","caller":"traceutil/trace.go:171","msg":"trace[288752875] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"207.78329ms","start":"2026-05-21T02:57:12.657893Z","end":"2026-05-21T02:57:12.865676Z","steps":["trace[288752875] 'process raft request' (duration: 206.592849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:12.866121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.326487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T02:57:12.866155Z","caller":"traceutil/trace.go:171","msg":"trace[623896715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"173.394248ms","start":"2026-05-21T02:57:12.692754Z","end":"2026-05-21T02:57:12.866148Z","steps":["trace[623896715] 'agreement among raft nodes before linearized reading' (duration: 173.321077ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:12.865535Z","caller":"traceutil/trace.go:171","msg":"trace[2107127717] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"172.739062ms","start":"2026-05-21T02:57:12.69278Z","end":"2026-05-21T02:57:12.865519Z","steps":["trace[2107127717] 'read index received' (duration: 85.691595ms)","trace[2107127717] 'applied index is now lower than readState.Index' (duration: 87.046427ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:57:12.883229Z","caller":"traceutil/trace.go:171","msg":"trace[1376040546] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"133.380746ms","start":"2026-05-21T02:57:12.749824Z","end":"2026-05-21T02:57:12.883204Z","steps":["trace[1376040546] 'process raft request' (duration: 129.204269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:12.924774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.352698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:57:12.924863Z","caller":"traceutil/trace.go:171","msg":"trace[1153942635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"215.49331ms","start":"2026-05-21T02:57:12.709348Z","end":"2026-05-21T02:57:12.924841Z","steps":["trace[1153942635] 'agreement among raft nodes before linearized reading' (duration: 215.296328ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:12.925504Z","caller":"traceutil/trace.go:171","msg":"trace[462088599] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"112.922883ms","start":"2026-05-21T02:57:12.812562Z","end":"2026-05-21T02:57:12.925485Z","steps":["trace[462088599] 'process raft request' (duration: 111.776422ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:13.075876Z","caller":"traceutil/trace.go:171","msg":"trace[137273617] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"114.587773ms","start":"2026-05-21T02:57:12.961258Z","end":"2026-05-21T02:57:13.075846Z","steps":["trace[137273617] 'read index received' (duration: 70.309775ms)","trace[137273617] 'applied index is now lower than readState.Index' (duration: 44.275788ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:57:13.076142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.862185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-21T02:57:13.076185Z","caller":"traceutil/trace.go:171","msg":"trace[1818179638] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:598; }","duration":"114.944636ms","start":"2026-05-21T02:57:12.961229Z","end":"2026-05-21T02:57:13.076173Z","steps":["trace[1818179638] 'agreement among raft nodes before linearized reading' (duration: 114.766454ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:13.076467Z","caller":"traceutil/trace.go:171","msg":"trace[1494300487] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"143.935838ms","start":"2026-05-21T02:57:12.932518Z","end":"2026-05-21T02:57:13.076453Z","steps":["trace[1494300487] 'process raft request' (duration: 99.118286ms)","trace[1494300487] 'compare' (duration: 44.095456ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:57:13.336171Z","caller":"traceutil/trace.go:171","msg":"trace[356797885] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"184.426348ms","start":"2026-05-21T02:57:13.15172Z","end":"2026-05-21T02:57:13.336146Z","steps":["trace[356797885] 'process raft request' (duration: 138.95002ms)","trace[356797885] 'compare' (duration: 45.170385ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:57:13.336179Z","caller":"traceutil/trace.go:171","msg":"trace[572177971] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"130.076859ms","start":"2026-05-21T02:57:13.206084Z","end":"2026-05-21T02:57:13.336161Z","steps":["trace[572177971] 'read index received' (duration: 84.54567ms)","trace[572177971] 'applied index is now lower than readState.Index' (duration: 45.528869ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:57:13.355051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.955939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-21T02:57:13.355108Z","caller":"traceutil/trace.go:171","msg":"trace[1504306376] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:601; }","duration":"149.05057ms","start":"2026-05-21T02:57:13.206041Z","end":"2026-05-21T02:57:13.355091Z","steps":["trace[1504306376] 'agreement among raft nodes before linearized reading' (duration: 130.17051ms)","trace[1504306376] 'range keys from in-memory index tree' (duration: 18.671148ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:57:18.608439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265973495431798,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T02:57:18.636604Z","caller":"traceutil/trace.go:171","msg":"trace[2108786514] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"561.895954ms","start":"2026-05-21T02:57:18.074683Z","end":"2026-05-21T02:57:18.636579Z","steps":["trace[2108786514] 'process raft request' (duration: 561.3443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:18.636868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:18.074659Z","time spent":"562.034895ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:57:18.809396Z","caller":"traceutil/trace.go:171","msg":"trace[2113924758] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"701.590274ms","start":"2026-05-21T02:57:18.107769Z","end":"2026-05-21T02:57:18.809359Z","steps":["trace[2113924758] 'read index received' (duration: 528.122749ms)","trace[2113924758] 'applied index is now lower than readState.Index' (duration: 173.466145ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:57:18.809461Z","caller":"traceutil/trace.go:171","msg":"trace[531415497] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"721.426817ms","start":"2026-05-21T02:57:18.087992Z","end":"2026-05-21T02:57:18.809419Z","steps":["trace[531415497] 'process raft request' (duration: 721.048704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:18.809617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.815305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-05-21T02:57:18.809701Z","caller":"traceutil/trace.go:171","msg":"trace[386834175] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:630; }","duration":"701.898406ms","start":"2026-05-21T02:57:18.107757Z","end":"2026-05-21T02:57:18.809656Z","steps":["trace[386834175] 'agreement among raft nodes before linearized reading' (duration: 701.761495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:18.80974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:18.107713Z","time spent":"702.018748ms","remote":"127.0.0.1:42064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-05-21T02:57:18.809924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:18.087973Z","time spent":"721.675359ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:57:18.81834Z","caller":"traceutil/trace.go:171","msg":"trace[1402597546] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"127.027208ms","start":"2026-05-21T02:57:18.691296Z","end":"2026-05-21T02:57:18.818323Z","steps":["trace[1402597546] 'process raft request' (duration: 126.782376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:18.818371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.207956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:57:18.818468Z","caller":"traceutil/trace.go:171","msg":"trace[2135798292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"508.321066ms","start":"2026-05-21T02:57:18.310123Z","end":"2026-05-21T02:57:18.818444Z","steps":["trace[2135798292] 'agreement among raft nodes before linearized reading' (duration: 508.155915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:18.818514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:18.310078Z","time spent":"508.428807ms","remote":"127.0.0.1:42036","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-05-21T02:57:20.603921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719760517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-21T02:57:20.603958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641843698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:57:20.604022Z","caller":"traceutil/trace.go:171","msg":"trace[1242784148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.719913659s","start":"2026-05-21T02:57:18.884086Z","end":"2026-05-21T02:57:20.604Z","steps":["trace[1242784148] 'range keys from in-memory index tree' (duration: 1.719574426s)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:20.604045Z","caller":"traceutil/trace.go:171","msg":"trace[1588081575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"1.641937939s","start":"2026-05-21T02:57:18.962081Z","end":"2026-05-21T02:57:20.604019Z","steps":["trace[1588081575] 'range keys from in-memory index tree' (duration: 1.641568655s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:20.604079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:18.884066Z","time spent":"1.720000259s","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T02:57:20.604098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:18.96207Z","time spent":"1.64201406s","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T02:57:20.604312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.711570335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T02:57:20.604336Z","caller":"traceutil/trace.go:171","msg":"trace[2131628928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.711606236s","start":"2026-05-21T02:57:18.892722Z","end":"2026-05-21T02:57:20.604328Z","steps":["trace[2131628928] 'range keys from in-memory index tree' (duration: 1.710993181s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:20.60436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:18.892713Z","time spent":"1.711638496s","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T02:57:20.603932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657512874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T02:57:20.60445Z","caller":"traceutil/trace.go:171","msg":"trace[1494571155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"1.658078398s","start":"2026-05-21T02:57:18.946361Z","end":"2026-05-21T02:57:20.60444Z","steps":["trace[1494571155] 'range keys from in-memory index tree' (duration: 1.657317143s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:20.604478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:18.946349Z","time spent":"1.65812172s","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T02:57:20.606749Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.353614084s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T02:57:20.60779Z","caller":"traceutil/trace.go:171","msg":"trace[92535126] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"297.375398ms","start":"2026-05-21T02:57:20.310394Z","end":"2026-05-21T02:57:20.607769Z","steps":["trace[92535126] 'read index received' (duration: 296.628972ms)","trace[92535126] 'applied index is now lower than readState.Index' (duration: 745.416µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:57:20.607874Z","caller":"traceutil/trace.go:171","msg":"trace[1043529072] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.355721392s","start":"2026-05-21T02:57:19.252134Z","end":"2026-05-21T02:57:20.607855Z","steps":["trace[1043529072] 'process raft request' (duration: 1.355080616s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:20.607938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.541629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:57:20.607967Z","caller":"traceutil/trace.go:171","msg":"trace[721780092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"297.656479ms","start":"2026-05-21T02:57:20.310303Z","end":"2026-05-21T02:57:20.60796Z","steps":["trace[721780092] 'agreement among raft nodes before linearized reading' (duration: 297.543779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:20.607985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:19.252111Z","time spent":"1.355804202s","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:57:37.399507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.626968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:57:37.399594Z","caller":"traceutil/trace.go:171","msg":"trace[1677132032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"699.759854ms","start":"2026-05-21T02:57:36.699815Z","end":"2026-05-21T02:57:37.399575Z","steps":["trace[1677132032] 'range keys from in-memory index tree' (duration: 699.447891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:37.399633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:36.699797Z","time spent":"699.827927ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T02:57:37.399658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.047821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T02:57:37.399747Z","caller":"traceutil/trace.go:171","msg":"trace[906764316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"695.168926ms","start":"2026-05-21T02:57:36.70456Z","end":"2026-05-21T02:57:37.399728Z","steps":["trace[906764316] 'range keys from in-memory index tree' (duration: 694.912156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:37.399792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:36.704549Z","time spent":"695.23458ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T02:57:37.399877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.165515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:57:37.399932Z","caller":"traceutil/trace.go:171","msg":"trace[712414816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"691.235598ms","start":"2026-05-21T02:57:36.708687Z","end":"2026-05-21T02:57:37.399923Z","steps":["trace[712414816] 'range keys from in-memory index tree' (duration: 691.085621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:37.399969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:36.708678Z","time spent":"691.2836ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T02:57:37.399567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089513202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:57:37.400361Z","caller":"traceutil/trace.go:171","msg":"trace[984637746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"1.090342076s","start":"2026-05-21T02:57:36.310003Z","end":"2026-05-21T02:57:37.400345Z","steps":["trace[984637746] 'range keys from in-memory index tree' (duration: 1.089404317s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:37.399943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.743603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T02:57:37.400835Z","caller":"traceutil/trace.go:171","msg":"trace[1233622886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"697.663853ms","start":"2026-05-21T02:57:36.703153Z","end":"2026-05-21T02:57:37.400816Z","steps":["trace[1233622886] 'range keys from in-memory index tree' (duration: 696.592477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:37.400887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:36.703142Z","time spent":"697.727466ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T02:57:37.400798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:57:36.309985Z","time spent":"1.090793406s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T02:57:37.407791Z","caller":"traceutil/trace.go:171","msg":"trace[1701939275] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"221.699469ms","start":"2026-05-21T02:57:37.18606Z","end":"2026-05-21T02:57:37.407759Z","steps":["trace[1701939275] 'process raft request' (duration: 221.459169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:57:37.408036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.587963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:57:37.408113Z","caller":"traceutil/trace.go:171","msg":"trace[1116710569] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:797; }","duration":"203.656015ms","start":"2026-05-21T02:57:37.204428Z","end":"2026-05-21T02:57:37.408084Z","steps":["trace[1116710569] 'agreement among raft nodes before linearized reading' (duration: 203.549761ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:57:37.407679Z","caller":"traceutil/trace.go:171","msg":"trace[1539009763] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"203.227248ms","start":"2026-05-21T02:57:37.204432Z","end":"2026-05-21T02:57:37.407659Z","steps":["trace[1539009763] 'read index received' (duration: 203.129143ms)","trace[1539009763] 'applied index is now lower than readState.Index' (duration: 97.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:57:59.924228Z","caller":"traceutil/trace.go:171","msg":"trace[767502107] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"246.366296ms","start":"2026-05-21T02:57:59.677834Z","end":"2026-05-21T02:57:59.9242Z","steps":["trace[767502107] 'process raft request' (duration: 213.177082ms)","trace[767502107] 'compare' (duration: 33.092141ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:58:00.210712Z","caller":"traceutil/trace.go:171","msg":"trace[1675096788] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"130.26478ms","start":"2026-05-21T02:58:00.080407Z","end":"2026-05-21T02:58:00.210672Z","steps":["trace[1675096788] 'process raft request' (duration: 129.95343ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:58:02.417251Z","caller":"traceutil/trace.go:171","msg":"trace[977217892] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"277.265724ms","start":"2026-05-21T02:58:02.139963Z","end":"2026-05-21T02:58:02.417228Z","steps":["trace[977217892] 'read index received' (duration: 277.120139ms)","trace[977217892] 'applied index is now lower than readState.Index' (duration: 144.505µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:58:02.417361Z","caller":"traceutil/trace.go:171","msg":"trace[329254982] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"326.830954ms","start":"2026-05-21T02:58:02.090517Z","end":"2026-05-21T02:58:02.417348Z","steps":["trace[329254982] 'process raft request' (duration: 326.570274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:02.417462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:02.090499Z","time spent":"326.886755ms","remote":"127.0.0.1:42522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2596,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:58:02.41765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.692608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/cert-manager-webhook\" ","response":"range_response_count:1 size:2558"} {"level":"info","ts":"2026-05-21T02:58:02.417689Z","caller":"traceutil/trace.go:171","msg":"trace[1252372336] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/cert-manager-webhook; range_end:; response_count:1; response_revision:1170; }","duration":"277.762001ms","start":"2026-05-21T02:58:02.139914Z","end":"2026-05-21T02:58:02.417676Z","steps":["trace[1252372336] 'agreement among raft nodes before linearized reading' (duration: 277.646907ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:58:02.463046Z","caller":"traceutil/trace.go:171","msg":"trace[1494309679] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"246.743409ms","start":"2026-05-21T02:58:02.216268Z","end":"2026-05-21T02:58:02.463012Z","steps":["trace[1494309679] 'process raft request' (duration: 246.463461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:02.463129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.254874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-jdlk8\" ","response":"range_response_count:1 size:3665"} {"level":"warn","ts":"2026-05-21T02:58:02.463164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.339668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:58:02.463232Z","caller":"traceutil/trace.go:171","msg":"trace[1851149236] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-jdlk8; range_end:; response_count:1; response_revision:1171; }","duration":"175.402069ms","start":"2026-05-21T02:58:02.287802Z","end":"2026-05-21T02:58:02.463204Z","steps":["trace[1851149236] 'agreement among raft nodes before linearized reading' (duration: 175.13506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:02.463261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.321409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T02:58:02.463308Z","caller":"traceutil/trace.go:171","msg":"trace[504866267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1171; }","duration":"234.403371ms","start":"2026-05-21T02:58:02.228891Z","end":"2026-05-21T02:58:02.463294Z","steps":["trace[504866267] 'agreement among raft nodes before linearized reading' (duration: 234.289368ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:58:02.463235Z","caller":"traceutil/trace.go:171","msg":"trace[1657634103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1171; }","duration":"154.457292ms","start":"2026-05-21T02:58:02.30876Z","end":"2026-05-21T02:58:02.463217Z","steps":["trace[1657634103] 'agreement among raft nodes before linearized reading' (duration: 154.350059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:08.984902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265973495432887,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T02:58:09.155832Z","caller":"traceutil/trace.go:171","msg":"trace[1325085863] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"673.566233ms","start":"2026-05-21T02:58:08.482241Z","end":"2026-05-21T02:58:09.155807Z","steps":["trace[1325085863] 'process raft request' (duration: 673.383808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:09.156128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:08.482213Z","time spent":"673.689477ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:58:09.29304Z","caller":"traceutil/trace.go:171","msg":"trace[1567088644] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1237; }","duration":"808.873362ms","start":"2026-05-21T02:58:08.48415Z","end":"2026-05-21T02:58:09.293023Z","steps":["trace[1567088644] 'read index received' (duration: 671.450639ms)","trace[1567088644] 'applied index is now lower than readState.Index' (duration: 137.421753ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:58:09.293139Z","caller":"traceutil/trace.go:171","msg":"trace[1993863537] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"699.227952ms","start":"2026-05-21T02:58:08.593894Z","end":"2026-05-21T02:58:09.293122Z","steps":["trace[1993863537] 'process raft request' (duration: 696.952453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:09.293218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:08.593872Z","time spent":"699.294764ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:58:09.293562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.324593ms","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-05-21T02:58:09.293602Z","caller":"traceutil/trace.go:171","msg":"trace[256567165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"775.408765ms","start":"2026-05-21T02:58:08.518184Z","end":"2026-05-21T02:58:09.293593Z","steps":["trace[256567165] 'agreement among raft nodes before linearized reading' (duration: 775.24447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:09.293636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:08.518161Z","time spent":"775.468567ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T02:58:09.293582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.387917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T02:58:09.293821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.392511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:58:09.293862Z","caller":"traceutil/trace.go:171","msg":"trace[1214576331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"496.467283ms","start":"2026-05-21T02:58:08.797385Z","end":"2026-05-21T02:58:09.293853Z","steps":["trace[1214576331] 'agreement among raft nodes before linearized reading' (duration: 496.410491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:09.293896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:08.797364Z","time spent":"496.523475ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T02:58:09.293958Z","caller":"traceutil/trace.go:171","msg":"trace[1710197053] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1204; }","duration":"809.729237ms","start":"2026-05-21T02:58:08.484109Z","end":"2026-05-21T02:58:09.293839Z","steps":["trace[1710197053] 'agreement among raft nodes before linearized reading' (duration: 809.241773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:09.294127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:08.484091Z","time spent":"810.013136ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T02:58:34.046502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.442919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T02:58:34.046563Z","caller":"traceutil/trace.go:171","msg":"trace[1178025292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1474; }","duration":"248.557161ms","start":"2026-05-21T02:58:33.797992Z","end":"2026-05-21T02:58:34.04655Z","steps":["trace[1178025292] 'range keys from in-memory index tree' (duration: 248.307736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:34.046643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.571779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T02:58:34.046619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.55811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:58:34.046706Z","caller":"traceutil/trace.go:171","msg":"trace[1779675912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1474; }","duration":"176.684274ms","start":"2026-05-21T02:58:33.870002Z","end":"2026-05-21T02:58:34.046686Z","steps":["trace[1779675912] 'range keys from in-memory index tree' (duration: 176.405177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:34.047057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.6627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:58:34.047077Z","caller":"traceutil/trace.go:171","msg":"trace[1713553425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1474; }","duration":"192.722872ms","start":"2026-05-21T02:58:33.854347Z","end":"2026-05-21T02:58:34.04707Z","steps":["trace[1713553425] 'range keys from in-memory index tree' (duration: 192.541637ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:58:34.046663Z","caller":"traceutil/trace.go:171","msg":"trace[1193402553] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1474; }","duration":"123.60421ms","start":"2026-05-21T02:58:33.923053Z","end":"2026-05-21T02:58:34.046657Z","steps":["trace[1193402553] 'count revisions from in-memory index tree' (duration: 123.503558ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:58:34.172966Z","caller":"traceutil/trace.go:171","msg":"trace[356582930] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"122.508828ms","start":"2026-05-21T02:58:34.05043Z","end":"2026-05-21T02:58:34.172938Z","steps":["trace[356582930] 'process raft request' (duration: 122.353894ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:58:34.659281Z","caller":"traceutil/trace.go:171","msg":"trace[68697278] linearizableReadLoop","detail":"{readStateIndex:1522; appliedIndex:1519; }","duration":"519.64288ms","start":"2026-05-21T02:58:34.139616Z","end":"2026-05-21T02:58:34.659259Z","steps":["trace[68697278] 'read index received' (duration: 33.194966ms)","trace[68697278] 'applied index is now lower than readState.Index' (duration: 486.446694ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:58:34.659336Z","caller":"traceutil/trace.go:171","msg":"trace[1827563181] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"608.04802ms","start":"2026-05-21T02:58:34.051249Z","end":"2026-05-21T02:58:34.659297Z","steps":["trace[1827563181] 'process raft request' (duration: 607.821325ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:58:34.659436Z","caller":"traceutil/trace.go:171","msg":"trace[1963767203] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"607.918966ms","start":"2026-05-21T02:58:34.051475Z","end":"2026-05-21T02:58:34.659393Z","steps":["trace[1963767203] 'process raft request' (duration: 607.741002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:34.659547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:34.051464Z","time spent":"608.002729ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:58:34.659564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:34.051226Z","time spent":"608.229714ms","remote":"127.0.0.1:42276","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-05-21T02:58:34.659779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.975062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:58:34.659857Z","caller":"traceutil/trace.go:171","msg":"trace[560624814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1477; }","duration":"350.068244ms","start":"2026-05-21T02:58:34.309771Z","end":"2026-05-21T02:58:34.65984Z","steps":["trace[560624814] 'agreement among raft nodes before linearized reading' (duration: 349.807358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:34.659845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.215103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T02:58:34.659902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:34.309715Z","time spent":"350.179066ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T02:58:34.659917Z","caller":"traceutil/trace.go:171","msg":"trace[1101295001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1477; }","duration":"520.357607ms","start":"2026-05-21T02:58:34.139542Z","end":"2026-05-21T02:58:34.6599Z","steps":["trace[1101295001] 'agreement among raft nodes before linearized reading' (duration: 520.04891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:34.659959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:34.139518Z","time spent":"520.432318ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T02:58:51.693437Z","caller":"traceutil/trace.go:171","msg":"trace[1642060804] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"144.441564ms","start":"2026-05-21T02:58:51.548971Z","end":"2026-05-21T02:58:51.693413Z","steps":["trace[1642060804] 'process raft request' (duration: 144.315641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:58.389289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.405008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:58:58.389366Z","caller":"traceutil/trace.go:171","msg":"trace[2008874706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1870; }","duration":"108.526081ms","start":"2026-05-21T02:58:58.280822Z","end":"2026-05-21T02:58:58.389348Z","steps":["trace[2008874706] 'range keys from in-memory index tree' (duration: 108.200965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:58.633216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.847138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-994eb79f-c5fb-4f63-a5a6-25f684534c81\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-05-21T02:58:58.633405Z","caller":"traceutil/trace.go:171","msg":"trace[779983887] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-994eb79f-c5fb-4f63-a5a6-25f684534c81; range_end:; response_count:1; response_revision:1871; }","duration":"127.105903ms","start":"2026-05-21T02:58:58.506242Z","end":"2026-05-21T02:58:58.633348Z","steps":["trace[779983887] 'range keys from in-memory index tree' (duration: 126.639314ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:58:58.939999Z","caller":"traceutil/trace.go:171","msg":"trace[1591389503] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"298.865947ms","start":"2026-05-21T02:58:58.641104Z","end":"2026-05-21T02:58:58.93997Z","steps":["trace[1591389503] 'process raft request' (duration: 298.706544ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:58:58.945908Z","caller":"traceutil/trace.go:171","msg":"trace[72073721] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1944; }","duration":"181.901328ms","start":"2026-05-21T02:58:58.763973Z","end":"2026-05-21T02:58:58.945874Z","steps":["trace[72073721] 'read index received' (duration: 175.929979ms)","trace[72073721] 'applied index is now lower than readState.Index' (duration: 5.970279ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:58:58.946019Z","caller":"traceutil/trace.go:171","msg":"trace[977139150] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"238.51447ms","start":"2026-05-21T02:58:58.707476Z","end":"2026-05-21T02:58:58.94599Z","steps":["trace[977139150] 'process raft request' (duration: 238.269775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:58.946239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.231184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:58:58.946373Z","caller":"traceutil/trace.go:171","msg":"trace[1493533712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1873; }","duration":"182.413749ms","start":"2026-05-21T02:58:58.763921Z","end":"2026-05-21T02:58:58.946334Z","steps":["trace[1493533712] 'agreement among raft nodes before linearized reading' (duration: 182.123742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:58.946497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.638784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T02:58:58.946551Z","caller":"traceutil/trace.go:171","msg":"trace[2038826791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1873; }","duration":"176.752846ms","start":"2026-05-21T02:58:58.769786Z","end":"2026-05-21T02:58:58.946538Z","steps":["trace[2038826791] 'agreement among raft nodes before linearized reading' (duration: 176.372028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:58.946575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.748426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T02:58:58.946608Z","caller":"traceutil/trace.go:171","msg":"trace[795586954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1873; }","duration":"174.826487ms","start":"2026-05-21T02:58:58.771772Z","end":"2026-05-21T02:58:58.946599Z","steps":["trace[795586954] 'agreement among raft nodes before linearized reading' (duration: 174.718355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:59.709099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.50879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:58:59.709223Z","caller":"traceutil/trace.go:171","msg":"trace[2080192553] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"759.26425ms","start":"2026-05-21T02:58:58.949941Z","end":"2026-05-21T02:58:59.709205Z","steps":["trace[2080192553] 'process raft request' (duration: 167.376872ms)","trace[2080192553] 'compare' (duration: 591.304257ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:58:59.709286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:58.949924Z","time spent":"759.331562ms","remote":"127.0.0.1:42276","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-05-21T02:58:59.725015Z","caller":"traceutil/trace.go:171","msg":"trace[435345572] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"774.464142ms","start":"2026-05-21T02:58:58.95053Z","end":"2026-05-21T02:58:59.724994Z","steps":["trace[435345572] 'process raft request' (duration: 774.227697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:59.725124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:58.950504Z","time spent":"774.570474ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:58:59.725422Z","caller":"traceutil/trace.go:171","msg":"trace[1833176905] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"774.791779ms","start":"2026-05-21T02:58:58.950599Z","end":"2026-05-21T02:58:59.725391Z","steps":["trace[1833176905] 'process raft request' (duration: 774.344739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:59.725565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:58.950586Z","time spent":"774.904912ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:58:59.725946Z","caller":"traceutil/trace.go:171","msg":"trace[1087925691] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"770.294129ms","start":"2026-05-21T02:58:58.955641Z","end":"2026-05-21T02:58:59.725935Z","steps":["trace[1087925691] 'process raft request' (duration: 769.549944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:59.726021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:58.95563Z","time spent":"770.35263ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10968,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:58:59.726131Z","caller":"traceutil/trace.go:171","msg":"trace[1235263940] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1947; }","duration":"489.435894ms","start":"2026-05-21T02:58:59.236662Z","end":"2026-05-21T02:58:59.726098Z","steps":["trace[1235263940] 'read index received' (duration: 488.063016ms)","trace[1235263940] 'applied index is now lower than readState.Index' (duration: 1.371528ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:58:59.726315Z","caller":"traceutil/trace.go:171","msg":"trace[1426287200] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"714.628767ms","start":"2026-05-21T02:58:59.011675Z","end":"2026-05-21T02:58:59.726304Z","steps":["trace[1426287200] 'process raft request' (duration: 714.212089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:59.726387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:59.011656Z","time spent":"714.696248ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:58:59.726676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.008855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-05-21T02:58:59.726735Z","caller":"traceutil/trace.go:171","msg":"trace[371777136] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1878; }","duration":"490.107327ms","start":"2026-05-21T02:58:59.236612Z","end":"2026-05-21T02:58:59.72672Z","steps":["trace[371777136] 'agreement among raft nodes before linearized reading' (duration: 489.839932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:59.726772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:59.236592Z","time spent":"490.171918ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-21T02:58:59.728566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.418391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T02:58:59.728625Z","caller":"traceutil/trace.go:171","msg":"trace[2096424471] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1879; }","duration":"430.518623ms","start":"2026-05-21T02:58:59.298093Z","end":"2026-05-21T02:58:59.728611Z","steps":["trace[2096424471] 'agreement among raft nodes before linearized reading' (duration: 430.354159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:59.728664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:59.298082Z","time spent":"430.572314ms","remote":"127.0.0.1:42188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-05-21T02:58:59.728866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.998674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T02:58:59.72887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.781436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-21T02:58:59.728905Z","caller":"traceutil/trace.go:171","msg":"trace[1038817051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1879; }","duration":"419.069065ms","start":"2026-05-21T02:58:59.309827Z","end":"2026-05-21T02:58:59.728896Z","steps":["trace[1038817051] 'agreement among raft nodes before linearized reading' (duration: 419.011314ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:58:59.72893Z","caller":"traceutil/trace.go:171","msg":"trace[626467107] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1879; }","duration":"295.880999ms","start":"2026-05-21T02:58:59.433031Z","end":"2026-05-21T02:58:59.728912Z","steps":["trace[626467107] 'agreement among raft nodes before linearized reading' (duration: 295.684625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:59.728936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:59.30981Z","time spent":"419.120467ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T02:58:59.731513Z","caller":"traceutil/trace.go:171","msg":"trace[513210877] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"363.967591ms","start":"2026-05-21T02:58:59.367533Z","end":"2026-05-21T02:58:59.731501Z","steps":["trace[513210877] 'process raft request' (duration: 360.039882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:58:59.731611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:58:59.367509Z","time spent":"364.035892ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:59:12.187496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.600311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:59:12.187885Z","caller":"traceutil/trace.go:171","msg":"trace[15606452] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"319.283603ms","start":"2026-05-21T02:59:11.868511Z","end":"2026-05-21T02:59:12.187794Z","steps":["trace[15606452] 'process raft request' (duration: 319.231622ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:12.187915Z","caller":"traceutil/trace.go:171","msg":"trace[1150942933] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"412.828714ms","start":"2026-05-21T02:59:11.775051Z","end":"2026-05-21T02:59:12.187879Z","steps":["trace[1150942933] 'process raft request' (duration: 412.560939ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:12.187924Z","caller":"traceutil/trace.go:171","msg":"trace[1053781639] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"418.59819ms","start":"2026-05-21T02:59:11.769291Z","end":"2026-05-21T02:59:12.187889Z","steps":["trace[1053781639] 'process raft request' (duration: 283.016111ms)","trace[1053781639] 'compare' (duration: 134.244475ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:59:12.187963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:11.868494Z","time spent":"319.432076ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:59:12.187919Z","caller":"traceutil/trace.go:171","msg":"trace[2000703205] linearizableReadLoop","detail":"{readStateIndex:2058; appliedIndex:2056; }","duration":"416.40829ms","start":"2026-05-21T02:59:11.771481Z","end":"2026-05-21T02:59:12.187889Z","steps":["trace[2000703205] 'read index received' (duration: 4.168646ms)","trace[2000703205] 'applied index is now lower than readState.Index' (duration: 412.230164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:59:12.188081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:11.769272Z","time spent":"418.732073ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:59:12.188107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:11.775037Z","time spent":"412.954107ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:59:12.188129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.621673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:59:12.188171Z","caller":"traceutil/trace.go:171","msg":"trace[1802486583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1984; }","duration":"416.710896ms","start":"2026-05-21T02:59:11.77145Z","end":"2026-05-21T02:59:12.188161Z","steps":["trace[1802486583] 'agreement among raft nodes before linearized reading' (duration: 416.525142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:12.18821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:11.771438Z","time spent":"416.761096ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T02:59:12.188282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.787243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-21T02:59:12.188327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.323413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-21T02:59:12.188358Z","caller":"traceutil/trace.go:171","msg":"trace[358950877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1984; }","duration":"393.885006ms","start":"2026-05-21T02:59:11.794449Z","end":"2026-05-21T02:59:12.188334Z","steps":["trace[358950877] 'agreement among raft nodes before linearized reading' (duration: 393.674962ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:12.18837Z","caller":"traceutil/trace.go:171","msg":"trace[868953718] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1984; }","duration":"375.383025ms","start":"2026-05-21T02:59:11.812972Z","end":"2026-05-21T02:59:12.188355Z","steps":["trace[868953718] 'agreement among raft nodes before linearized reading' (duration: 375.241683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:12.188371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.959639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-05-21T02:59:12.188386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.424173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T02:59:12.188406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:11.812957Z","time spent":"375.441358ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-21T02:59:12.188404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:11.794442Z","time spent":"393.952848ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T02:59:12.188414Z","caller":"traceutil/trace.go:171","msg":"trace[1256096263] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1984; }","duration":"386.021671ms","start":"2026-05-21T02:59:11.802379Z","end":"2026-05-21T02:59:12.1884Z","steps":["trace[1256096263] 'agreement among raft nodes before linearized reading' (duration: 385.875847ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:12.188421Z","caller":"traceutil/trace.go:171","msg":"trace[1106987966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1984; }","duration":"390.470373ms","start":"2026-05-21T02:59:11.797942Z","end":"2026-05-21T02:59:12.188412Z","steps":["trace[1106987966] 'agreement among raft nodes before linearized reading' (duration: 390.378651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:12.188446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:11.802368Z","time spent":"386.072353ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-21T02:59:12.188451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:11.797936Z","time spent":"390.509285ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T02:59:12.297259Z","caller":"traceutil/trace.go:171","msg":"trace[676141335] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"104.422259ms","start":"2026-05-21T02:59:12.192813Z","end":"2026-05-21T02:59:12.297236Z","steps":["trace[676141335] 'process raft request' (duration: 94.877035ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:12.297664Z","caller":"traceutil/trace.go:171","msg":"trace[839371948] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"104.738226ms","start":"2026-05-21T02:59:12.192902Z","end":"2026-05-21T02:59:12.29764Z","steps":["trace[839371948] 'process raft request' (duration: 104.289447ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:12.298579Z","caller":"traceutil/trace.go:171","msg":"trace[1551144212] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"104.148224ms","start":"2026-05-21T02:59:12.194344Z","end":"2026-05-21T02:59:12.298492Z","steps":["trace[1551144212] 'process raft request' (duration: 103.162415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:16.317636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265973495434551,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T02:59:16.338771Z","caller":"traceutil/trace.go:171","msg":"trace[1976483797] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"549.65382ms","start":"2026-05-21T02:59:15.789093Z","end":"2026-05-21T02:59:16.338747Z","steps":["trace[1976483797] 'process raft request' (duration: 549.479447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:16.338955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:15.789077Z","time spent":"549.773683ms","remote":"127.0.0.1:42276","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-05-21T02:59:16.34031Z","caller":"traceutil/trace.go:171","msg":"trace[671538653] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2089; }","duration":"523.085493ms","start":"2026-05-21T02:59:15.817205Z","end":"2026-05-21T02:59:16.34029Z","steps":["trace[671538653] 'read index received' (duration: 521.554365ms)","trace[671538653] 'applied index is now lower than readState.Index' (duration: 1.530188ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:59:16.34058Z","caller":"traceutil/trace.go:171","msg":"trace[1164101604] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"140.370567ms","start":"2026-05-21T02:59:16.200093Z","end":"2026-05-21T02:59:16.340464Z","steps":["trace[1164101604] 'process raft request' (duration: 140.158373ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:16.340857Z","caller":"traceutil/trace.go:171","msg":"trace[1290836093] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"140.748524ms","start":"2026-05-21T02:59:16.200087Z","end":"2026-05-21T02:59:16.340836Z","steps":["trace[1290836093] 'process raft request' (duration: 140.115913ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:16.340968Z","caller":"traceutil/trace.go:171","msg":"trace[416605129] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"141.248113ms","start":"2026-05-21T02:59:16.19971Z","end":"2026-05-21T02:59:16.340958Z","steps":["trace[416605129] 'process raft request' (duration: 140.325307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:16.341436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.212363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-21T02:59:16.341496Z","caller":"traceutil/trace.go:171","msg":"trace[1813011823] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2015; }","duration":"524.288084ms","start":"2026-05-21T02:59:15.817193Z","end":"2026-05-21T02:59:16.341481Z","steps":["trace[1813011823] 'agreement among raft nodes before linearized reading' (duration: 523.344508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:16.341529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:15.817162Z","time spent":"524.358345ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-21T02:59:16.788879Z","caller":"traceutil/trace.go:171","msg":"trace[790217183] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"442.980383ms","start":"2026-05-21T02:59:16.34588Z","end":"2026-05-21T02:59:16.78886Z","steps":["trace[790217183] 'process raft request' (duration: 438.562834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:16.789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:16.345861Z","time spent":"443.073375ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:59:16.789893Z","caller":"traceutil/trace.go:171","msg":"trace[1656453491] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"440.490298ms","start":"2026-05-21T02:59:16.349395Z","end":"2026-05-21T02:59:16.789885Z","steps":["trace[1656453491] 'process raft request' (duration: 440.417207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:16.789939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:16.349372Z","time spent":"440.546919ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:59:16.790024Z","caller":"traceutil/trace.go:171","msg":"trace[1527334432] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"440.556839ms","start":"2026-05-21T02:59:16.349461Z","end":"2026-05-21T02:59:16.790018Z","steps":["trace[1527334432] 'process raft request' (duration: 440.398577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:16.790057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:16.34944Z","time spent":"440.60161ms","remote":"127.0.0.1:42276","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-05-21T02:59:16.790236Z","caller":"traceutil/trace.go:171","msg":"trace[1592296565] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"440.531719ms","start":"2026-05-21T02:59:16.349692Z","end":"2026-05-21T02:59:16.790224Z","steps":["trace[1592296565] 'process raft request' (duration: 440.300635ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:16.790247Z","caller":"traceutil/trace.go:171","msg":"trace[538823931] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"440.446467ms","start":"2026-05-21T02:59:16.349788Z","end":"2026-05-21T02:59:16.790235Z","steps":["trace[538823931] 'process raft request' (duration: 440.333325ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:16.790225Z","caller":"traceutil/trace.go:171","msg":"trace[286818359] linearizableReadLoop","detail":"{readStateIndex:2097; appliedIndex:2092; }","duration":"246.502594ms","start":"2026-05-21T02:59:16.5437Z","end":"2026-05-21T02:59:16.790202Z","steps":["trace[286818359] 'read index received' (duration: 240.817922ms)","trace[286818359] 'applied index is now lower than readState.Index' (duration: 5.683572ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:59:16.790286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:16.349687Z","time spent":"440.57244ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:59:16.7903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:16.349783Z","time spent":"440.489208ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:59:16.790358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.655416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:59:16.790384Z","caller":"traceutil/trace.go:171","msg":"trace[898281272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2020; }","duration":"246.711317ms","start":"2026-05-21T02:59:16.543665Z","end":"2026-05-21T02:59:16.790377Z","steps":["trace[898281272] 'agreement among raft nodes before linearized reading' (duration: 246.626345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:16.790441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.436036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-21T02:59:16.790475Z","caller":"traceutil/trace.go:171","msg":"trace[1507877538] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2020; }","duration":"113.527387ms","start":"2026-05-21T02:59:16.676938Z","end":"2026-05-21T02:59:16.790465Z","steps":["trace[1507877538] 'agreement among raft nodes before linearized reading' (duration: 113.383225ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:33.684686Z","caller":"traceutil/trace.go:171","msg":"trace[1752134406] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"163.916737ms","start":"2026-05-21T02:59:33.520745Z","end":"2026-05-21T02:59:33.684662Z","steps":["trace[1752134406] 'process raft request' (duration: 163.805145ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:39.131279Z","caller":"traceutil/trace.go:171","msg":"trace[1813789304] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"240.452747ms","start":"2026-05-21T02:59:38.890793Z","end":"2026-05-21T02:59:39.131246Z","steps":["trace[1813789304] 'process raft request' (duration: 240.270964ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:39.212072Z","caller":"traceutil/trace.go:171","msg":"trace[1029040299] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2222; }","duration":"316.940521ms","start":"2026-05-21T02:59:38.895109Z","end":"2026-05-21T02:59:39.21205Z","steps":["trace[1029040299] 'read index received' (duration: 235.984155ms)","trace[1029040299] 'applied index is now lower than readState.Index' (duration: 80.955526ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:59:39.212116Z","caller":"traceutil/trace.go:171","msg":"trace[2133301980] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"318.990564ms","start":"2026-05-21T02:59:38.893087Z","end":"2026-05-21T02:59:39.212078Z","steps":["trace[2133301980] 'process raft request' (duration: 266.112844ms)","trace[2133301980] 'compare' (duration: 52.749488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:59:39.21223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:38.893067Z","time spent":"319.104726ms","remote":"127.0.0.1:42276","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-05-21T02:59:39.212291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.89279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:59:39.21233Z","caller":"traceutil/trace.go:171","msg":"trace[623172614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2142; }","duration":"316.972691ms","start":"2026-05-21T02:59:38.895347Z","end":"2026-05-21T02:59:39.21232Z","steps":["trace[623172614] 'agreement among raft nodes before linearized reading' (duration: 316.830399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:39.21237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.227165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-21T02:59:39.212455Z","caller":"traceutil/trace.go:171","msg":"trace[494615125] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2142; }","duration":"317.389238ms","start":"2026-05-21T02:59:38.895048Z","end":"2026-05-21T02:59:39.212437Z","steps":["trace[494615125] 'agreement among raft nodes before linearized reading' (duration: 317.102764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:39.212489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:38.89503Z","time spent":"317.451509ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-21T02:59:39.212598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.291246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T02:59:39.212371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:38.895335Z","time spent":"317.023872ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T02:59:39.212634Z","caller":"traceutil/trace.go:171","msg":"trace[1691693465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2142; }","duration":"312.346597ms","start":"2026-05-21T02:59:38.90028Z","end":"2026-05-21T02:59:39.212627Z","steps":["trace[1691693465] 'agreement among raft nodes before linearized reading' (duration: 312.246605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:39.212651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:38.900271Z","time spent":"312.376107ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T02:59:40.541615Z","caller":"traceutil/trace.go:171","msg":"trace[725443294] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"100.489869ms","start":"2026-05-21T02:59:40.441101Z","end":"2026-05-21T02:59:40.54159Z","steps":["trace[725443294] 'process raft request' (duration: 100.264295ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:40.717815Z","caller":"traceutil/trace.go:171","msg":"trace[2098811128] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"276.534563ms","start":"2026-05-21T02:59:40.441256Z","end":"2026-05-21T02:59:40.71779Z","steps":["trace[2098811128] 'process raft request' (duration: 229.718482ms)","trace[2098811128] 'compare' (duration: 46.257331ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:59:40.717827Z","caller":"traceutil/trace.go:171","msg":"trace[1647512382] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"276.083705ms","start":"2026-05-21T02:59:40.441724Z","end":"2026-05-21T02:59:40.717807Z","steps":["trace[1647512382] 'process raft request' (duration: 275.853941ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:40.717832Z","caller":"traceutil/trace.go:171","msg":"trace[1040982298] linearizableReadLoop","detail":"{readStateIndex:2230; appliedIndex:2227; }","duration":"269.257977ms","start":"2026-05-21T02:59:40.448554Z","end":"2026-05-21T02:59:40.717812Z","steps":["trace[1040982298] 'read index received' (duration: 92.886999ms)","trace[1040982298] 'applied index is now lower than readState.Index' (duration: 176.367818ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:59:40.718045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.48502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T02:59:40.718065Z","caller":"traceutil/trace.go:171","msg":"trace[312394644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2149; }","duration":"269.556802ms","start":"2026-05-21T02:59:40.448502Z","end":"2026-05-21T02:59:40.718059Z","steps":["trace[312394644] 'agreement among raft nodes before linearized reading' (duration: 269.41568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:40.718153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.368752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-21T02:59:40.718185Z","caller":"traceutil/trace.go:171","msg":"trace[20951644] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2149; }","duration":"230.409303ms","start":"2026-05-21T02:59:40.487765Z","end":"2026-05-21T02:59:40.718174Z","steps":["trace[20951644] 'agreement among raft nodes before linearized reading' (duration: 230.301991ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:41.708205Z","caller":"traceutil/trace.go:171","msg":"trace[1998502067] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2232; }","duration":"491.608647ms","start":"2026-05-21T02:59:41.216573Z","end":"2026-05-21T02:59:41.708181Z","steps":["trace[1998502067] 'read index received' (duration: 491.354143ms)","trace[1998502067] 'applied index is now lower than readState.Index' (duration: 253.624µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:59:41.708255Z","caller":"traceutil/trace.go:171","msg":"trace[1263189419] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"517.318092ms","start":"2026-05-21T02:59:41.190903Z","end":"2026-05-21T02:59:41.708221Z","steps":["trace[1263189419] 'process raft request' (duration: 517.109479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:41.708393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.19088Z","time spent":"517.438954ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:59:41.708433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.847501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:59:41.708468Z","caller":"traceutil/trace.go:171","msg":"trace[1663506360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2151; }","duration":"491.938972ms","start":"2026-05-21T02:59:41.216519Z","end":"2026-05-21T02:59:41.708458Z","steps":["trace[1663506360] 'agreement among raft nodes before linearized reading' (duration: 491.78654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:41.708514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.216498Z","time spent":"492.007034ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T02:59:41.708794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.50288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-05-21T02:59:41.708839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.686057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:59:41.708887Z","caller":"traceutil/trace.go:171","msg":"trace[435451209] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2151; }","duration":"396.643732ms","start":"2026-05-21T02:59:41.31222Z","end":"2026-05-21T02:59:41.708863Z","steps":["trace[435451209] 'agreement among raft nodes before linearized reading' (duration: 396.358178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:41.708944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.312199Z","time spent":"396.736043ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-21T02:59:41.709124Z","caller":"traceutil/trace.go:171","msg":"trace[1765833519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2151; }","duration":"416.84763ms","start":"2026-05-21T02:59:41.292078Z","end":"2026-05-21T02:59:41.708926Z","steps":["trace[1765833519] 'agreement among raft nodes before linearized reading' (duration: 416.486575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:41.709193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.292054Z","time spent":"417.125325ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T02:59:41.70951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.751377ms","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-05-21T02:59:41.709567Z","caller":"traceutil/trace.go:171","msg":"trace[1214262573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2151; }","duration":"398.861839ms","start":"2026-05-21T02:59:41.310691Z","end":"2026-05-21T02:59:41.709553Z","steps":["trace[1214262573] 'agreement among raft nodes before linearized reading' (duration: 398.527724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:41.709599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.310677Z","time spent":"398.91433ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T02:59:42.67534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.922349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:59:42.675702Z","caller":"traceutil/trace.go:171","msg":"trace[544685736] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"963.846715ms","start":"2026-05-21T02:59:41.711816Z","end":"2026-05-21T02:59:42.675662Z","steps":["trace[544685736] 'process raft request' (duration: 150.513609ms)","trace[544685736] 'compare' (duration: 812.621774ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:59:42.675854Z","caller":"traceutil/trace.go:171","msg":"trace[1493038539] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"128.87212ms","start":"2026-05-21T02:59:42.54696Z","end":"2026-05-21T02:59:42.675832Z","steps":["trace[1493038539] 'process raft request' (duration: 128.777408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:42.67591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.711787Z","time spent":"964.008128ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:59:42.676257Z","caller":"traceutil/trace.go:171","msg":"trace[773474090] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"962.402102ms","start":"2026-05-21T02:59:41.713844Z","end":"2026-05-21T02:59:42.676246Z","steps":["trace[773474090] 'process raft request' (duration: 961.706701ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:42.676337Z","caller":"traceutil/trace.go:171","msg":"trace[1535856264] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"962.415612ms","start":"2026-05-21T02:59:41.713909Z","end":"2026-05-21T02:59:42.676325Z","steps":["trace[1535856264] 'process raft request' (duration: 961.722042ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:42.676411Z","caller":"traceutil/trace.go:171","msg":"trace[1629261341] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"962.446722ms","start":"2026-05-21T02:59:41.713954Z","end":"2026-05-21T02:59:42.676401Z","steps":["trace[1629261341] 'process raft request' (duration: 961.715471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:42.67652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.713929Z","time spent":"962.512404ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:59:42.676602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.713901Z","time spent":"962.473673ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:59:42.67642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.713829Z","time spent":"962.510123ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:59:42.676772Z","caller":"traceutil/trace.go:171","msg":"trace[1077305231] linearizableReadLoop","detail":"{readStateIndex:2238; appliedIndex:2233; }","duration":"952.978073ms","start":"2026-05-21T02:59:41.723782Z","end":"2026-05-21T02:59:42.67676Z","steps":["trace[1077305231] 'read index received' (duration: 138.55827ms)","trace[1077305231] 'applied index is now lower than readState.Index' (duration: 814.418703ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:59:42.676275Z","caller":"traceutil/trace.go:171","msg":"trace[440955014] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"962.063277ms","start":"2026-05-21T02:59:41.714199Z","end":"2026-05-21T02:59:42.676262Z","steps":["trace[440955014] 'process raft request' (duration: 961.503739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:42.676943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.974801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T02:59:42.67704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.71419Z","time spent":"962.776668ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:59:42.677131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.047691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T02:59:42.67716Z","caller":"traceutil/trace.go:171","msg":"trace[1254823861] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2157; }","duration":"358.123553ms","start":"2026-05-21T02:59:42.31903Z","end":"2026-05-21T02:59:42.677154Z","steps":["trace[1254823861] 'agreement among raft nodes before linearized reading' (duration: 358.049472ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:42.677004Z","caller":"traceutil/trace.go:171","msg":"trace[1500281758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2157; }","duration":"367.092492ms","start":"2026-05-21T02:59:42.309897Z","end":"2026-05-21T02:59:42.67699Z","steps":["trace[1500281758] 'agreement among raft nodes before linearized reading' (duration: 367.007921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:42.677235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:42.309871Z","time spent":"367.353197ms","remote":"127.0.0.1:42036","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-05-21T02:59:42.677292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.510081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-21T02:59:42.677315Z","caller":"traceutil/trace.go:171","msg":"trace[304340919] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2157; }","duration":"953.556682ms","start":"2026-05-21T02:59:41.723751Z","end":"2026-05-21T02:59:42.677307Z","steps":["trace[304340919] 'agreement among raft nodes before linearized reading' (duration: 953.456491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:42.677334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:41.723738Z","time spent":"953.590973ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-21T02:59:42.677184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:42.318976Z","time spent":"358.200473ms","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-21T02:59:42.679184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.100346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12384"} {"level":"info","ts":"2026-05-21T02:59:42.679228Z","caller":"traceutil/trace.go:171","msg":"trace[692362153] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2157; }","duration":"214.200977ms","start":"2026-05-21T02:59:42.465016Z","end":"2026-05-21T02:59:42.679217Z","steps":["trace[692362153] 'agreement among raft nodes before linearized reading' (duration: 212.090334ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:59:43.19462Z","caller":"traceutil/trace.go:171","msg":"trace[122652564] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"472.38445ms","start":"2026-05-21T02:59:42.722207Z","end":"2026-05-21T02:59:43.194591Z","steps":["trace[122652564] 'process raft request' (duration: 426.049277ms)","trace[122652564] 'compare' (duration: 45.912727ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:59:43.195025Z","caller":"traceutil/trace.go:171","msg":"trace[1575319933] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"472.068106ms","start":"2026-05-21T02:59:42.722894Z","end":"2026-05-21T02:59:43.194962Z","steps":["trace[1575319933] 'process raft request' (duration: 471.71232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:43.195081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:42.722187Z","time spent":"472.589144ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:59:43.195259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:42.722879Z","time spent":"472.227848ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:59:43.196462Z","caller":"traceutil/trace.go:171","msg":"trace[278755096] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2239; }","duration":"437.682756ms","start":"2026-05-21T02:59:42.758763Z","end":"2026-05-21T02:59:43.196446Z","steps":["trace[278755096] 'read index received' (duration: 389.479694ms)","trace[278755096] 'applied index is now lower than readState.Index' (duration: 48.202092ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:59:43.196732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.958241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T02:59:43.196799Z","caller":"traceutil/trace.go:171","msg":"trace[1801587007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2159; }","duration":"438.047532ms","start":"2026-05-21T02:59:42.758738Z","end":"2026-05-21T02:59:43.196786Z","steps":["trace[1801587007] 'agreement among raft nodes before linearized reading' (duration: 437.767937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:59:43.196832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:59:42.758727Z","time spent":"438.096313ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T03:01:32.238255Z","caller":"traceutil/trace.go:171","msg":"trace[1891899674] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"104.275592ms","start":"2026-05-21T03:01:32.133946Z","end":"2026-05-21T03:01:32.238222Z","steps":["trace[1891899674] 'process raft request' (duration: 104.07306ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:01:36.58617Z","caller":"traceutil/trace.go:171","msg":"trace[62916106] linearizableReadLoop","detail":"{readStateIndex:2978; appliedIndex:2977; }","duration":"294.555512ms","start":"2026-05-21T03:01:36.291596Z","end":"2026-05-21T03:01:36.586152Z","steps":["trace[62916106] 'read index received' (duration: 294.41907ms)","trace[62916106] 'applied index is now lower than readState.Index' (duration: 135.682µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:01:36.586473Z","caller":"traceutil/trace.go:171","msg":"trace[1288618998] transaction","detail":"{read_only:false; response_revision:2870; number_of_response:1; }","duration":"322.108004ms","start":"2026-05-21T03:01:36.264337Z","end":"2026-05-21T03:01:36.586445Z","steps":["trace[1288618998] 'process raft request' (duration: 321.702819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:36.586632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:36.264311Z","time spent":"322.252035ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:01:36.586875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.730354ms","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-05-21T03:01:36.58691Z","caller":"traceutil/trace.go:171","msg":"trace[1998396120] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2870; }","duration":"295.351231ms","start":"2026-05-21T03:01:36.29155Z","end":"2026-05-21T03:01:36.586901Z","steps":["trace[1998396120] 'agreement among raft nodes before linearized reading' (duration: 294.679673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:51.821724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265973495436928,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:01:52.06764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.186271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:01:52.067717Z","caller":"traceutil/trace.go:171","msg":"trace[2057598457] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2945; }","duration":"885.320116ms","start":"2026-05-21T03:01:51.182383Z","end":"2026-05-21T03:01:52.067703Z","steps":["trace[2057598457] 'count revisions from in-memory index tree' (duration: 885.100279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.067749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.182359Z","time spent":"885.382597ms","remote":"127.0.0.1:47346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:01:52.068183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.503961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:01:52.068211Z","caller":"traceutil/trace.go:171","msg":"trace[1307631289] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2945; }","duration":"868.589543ms","start":"2026-05-21T03:01:51.199614Z","end":"2026-05-21T03:01:52.068204Z","steps":["trace[1307631289] 'count revisions from in-memory index tree' (duration: 868.399646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.068312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.199582Z","time spent":"868.663205ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-05-21T03:01:52.069328Z","caller":"traceutil/trace.go:171","msg":"trace[1569526383] transaction","detail":"{read_only:false; response_revision:2946; number_of_response:1; }","duration":"779.542767ms","start":"2026-05-21T03:01:51.289776Z","end":"2026-05-21T03:01:52.069318Z","steps":["trace[1569526383] 'process raft request' (duration: 774.56821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.069513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.289744Z","time spent":"779.611899ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:01:52.069992Z","caller":"traceutil/trace.go:171","msg":"trace[805487050] transaction","detail":"{read_only:false; response_revision:2947; number_of_response:1; }","duration":"446.562393ms","start":"2026-05-21T03:01:51.623396Z","end":"2026-05-21T03:01:52.069958Z","steps":["trace[805487050] 'process raft request' (duration: 446.136418ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:01:52.070086Z","caller":"traceutil/trace.go:171","msg":"trace[1656495617] transaction","detail":"{read_only:false; response_revision:2948; number_of_response:1; }","duration":"418.093006ms","start":"2026-05-21T03:01:51.651984Z","end":"2026-05-21T03:01:52.070077Z","steps":["trace[1656495617] 'process raft request' (duration: 417.89364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.070212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.651952Z","time spent":"418.156708ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:01:52.070455Z","caller":"traceutil/trace.go:171","msg":"trace[1061540650] linearizableReadLoop","detail":"{readStateIndex:3060; appliedIndex:3056; }","duration":"749.589863ms","start":"2026-05-21T03:01:51.320856Z","end":"2026-05-21T03:01:52.070446Z","steps":["trace[1061540650] 'read index received' (duration: 743.634184ms)","trace[1061540650] 'applied index is now lower than readState.Index' (duration: 5.954899ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:01:52.070515Z","caller":"traceutil/trace.go:171","msg":"trace[463270994] transaction","detail":"{read_only:false; response_revision:2949; number_of_response:1; }","duration":"395.838707ms","start":"2026-05-21T03:01:51.674656Z","end":"2026-05-21T03:01:52.070495Z","steps":["trace[463270994] 'process raft request' (duration: 395.386673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.070705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.674635Z","time spent":"395.93688ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:01:52.070753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.903332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:01:52.070806Z","caller":"traceutil/trace.go:171","msg":"trace[790372813] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2949; }","duration":"749.961035ms","start":"2026-05-21T03:01:51.320836Z","end":"2026-05-21T03:01:52.070797Z","steps":["trace[790372813] 'agreement among raft nodes before linearized reading' (duration: 749.670736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.070871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.320815Z","time spent":"750.046007ms","remote":"127.0.0.1:46510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:01:52.070899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.873962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-21T03:01:52.070924Z","caller":"traceutil/trace.go:171","msg":"trace[1536539148] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2949; }","duration":"657.929474ms","start":"2026-05-21T03:01:51.412988Z","end":"2026-05-21T03:01:52.070917Z","steps":["trace[1536539148] 'agreement among raft nodes before linearized reading' (duration: 657.834921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.070842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.623365Z","time spent":"446.723118ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:01:52.070953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.284883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-21T03:01:52.070996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.339566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-21T03:01:52.071015Z","caller":"traceutil/trace.go:171","msg":"trace[1913770555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2949; }","duration":"214.397549ms","start":"2026-05-21T03:01:51.856612Z","end":"2026-05-21T03:01:52.07101Z","steps":["trace[1913770555] 'agreement among raft nodes before linearized reading' (duration: 214.344727ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:01:52.07103Z","caller":"traceutil/trace.go:171","msg":"trace[731211271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2949; }","duration":"733.412018ms","start":"2026-05-21T03:01:51.3376Z","end":"2026-05-21T03:01:52.071012Z","steps":["trace[731211271] 'agreement among raft nodes before linearized reading' (duration: 733.18098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.071103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.957359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-21T03:01:52.071107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.337576Z","time spent":"733.51748ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T03:01:52.071132Z","caller":"traceutil/trace.go:171","msg":"trace[635916992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2949; }","duration":"731.022061ms","start":"2026-05-21T03:01:51.340092Z","end":"2026-05-21T03:01:52.071114Z","steps":["trace[635916992] 'agreement among raft nodes before linearized reading' (duration: 730.974889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.071149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.340066Z","time spent":"731.079103ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T03:01:52.071226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.89715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-21T03:01:52.071249Z","caller":"traceutil/trace.go:171","msg":"trace[661430391] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2949; }","duration":"705.956903ms","start":"2026-05-21T03:01:51.365285Z","end":"2026-05-21T03:01:52.071242Z","steps":["trace[661430391] 'agreement among raft nodes before linearized reading' (duration: 705.905071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.071265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.365272Z","time spent":"705.989473ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-21T03:01:52.071309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.440766ms","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-05-21T03:01:52.07136Z","caller":"traceutil/trace.go:171","msg":"trace[982773424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2949; }","duration":"732.500457ms","start":"2026-05-21T03:01:51.338845Z","end":"2026-05-21T03:01:52.071346Z","steps":["trace[982773424] 'agreement among raft nodes before linearized reading' (duration: 732.379833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.0714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.338839Z","time spent":"732.550359ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T03:01:52.070945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:01:51.41297Z","time spent":"657.970954ms","remote":"127.0.0.1:42204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-21T03:01:52.071617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.533887ms","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-05-21T03:01:52.07171Z","caller":"traceutil/trace.go:171","msg":"trace[893510810] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2949; }","duration":"195.685602ms","start":"2026-05-21T03:01:51.876007Z","end":"2026-05-21T03:01:52.071692Z","steps":["trace[893510810] 'agreement among raft nodes before linearized reading' (duration: 194.831205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:01:52.071666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.862878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:01:52.071898Z","caller":"traceutil/trace.go:171","msg":"trace[1234857022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2949; }","duration":"175.149097ms","start":"2026-05-21T03:01:51.896735Z","end":"2026-05-21T03:01:52.071884Z","steps":["trace[1234857022] 'agreement among raft nodes before linearized reading' (duration: 174.882619ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:12.89639Z","caller":"traceutil/trace.go:171","msg":"trace[580166012] linearizableReadLoop","detail":"{readStateIndex:3495; appliedIndex:3494; }","duration":"200.751375ms","start":"2026-05-21T03:03:12.695595Z","end":"2026-05-21T03:03:12.896346Z","steps":["trace[580166012] 'read index received' (duration: 200.594501ms)","trace[580166012] 'applied index is now lower than readState.Index' (duration: 156.314µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:03:12.89671Z","caller":"traceutil/trace.go:171","msg":"trace[2077608332] transaction","detail":"{read_only:false; response_revision:3367; number_of_response:1; }","duration":"224.372094ms","start":"2026-05-21T03:03:12.672329Z","end":"2026-05-21T03:03:12.896701Z","steps":["trace[2077608332] 'process raft request' (duration: 223.922244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:12.896772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-21T03:03:12.896833Z","caller":"traceutil/trace.go:171","msg":"trace[2136081025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3367; }","duration":"198.553293ms","start":"2026-05-21T03:03:12.698265Z","end":"2026-05-21T03:03:12.896818Z","steps":["trace[2136081025] 'agreement among raft nodes before linearized reading' (duration: 198.350799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:12.896981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.383959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T03:03:12.896999Z","caller":"traceutil/trace.go:171","msg":"trace[207853589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3367; }","duration":"201.426731ms","start":"2026-05-21T03:03:12.695568Z","end":"2026-05-21T03:03:12.896994Z","steps":["trace[207853589] 'agreement among raft nodes before linearized reading' (duration: 201.371789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:12.897177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.01152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-21T03:03:12.897198Z","caller":"traceutil/trace.go:171","msg":"trace[1733011754] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3367; }","duration":"195.050601ms","start":"2026-05-21T03:03:12.702141Z","end":"2026-05-21T03:03:12.897192Z","steps":["trace[1733011754] 'agreement among raft nodes before linearized reading' (duration: 194.98588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:12.897312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.777232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T03:03:12.897329Z","caller":"traceutil/trace.go:171","msg":"trace[1475863407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3367; }","duration":"196.816102ms","start":"2026-05-21T03:03:12.700509Z","end":"2026-05-21T03:03:12.897325Z","steps":["trace[1475863407] 'agreement among raft nodes before linearized reading' (duration: 196.772181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:13.710973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.585005ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:03:13.711119Z","caller":"traceutil/trace.go:171","msg":"trace[833863472] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3371; }","duration":"200.736709ms","start":"2026-05-21T03:03:13.51036Z","end":"2026-05-21T03:03:13.711097Z","steps":["trace[833863472] 'range keys from in-memory index tree' (duration: 200.571715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:13.711117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.061282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T03:03:13.711207Z","caller":"traceutil/trace.go:171","msg":"trace[377900137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3371; }","duration":"617.194925ms","start":"2026-05-21T03:03:13.093996Z","end":"2026-05-21T03:03:13.711191Z","steps":["trace[377900137] 'range keys from in-memory index tree' (duration: 616.895357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:13.711252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:13.093977Z","time spent":"617.267086ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T03:03:13.711697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.994848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:03:13.711861Z","caller":"traceutil/trace.go:171","msg":"trace[426954472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3371; }","duration":"386.183943ms","start":"2026-05-21T03:03:13.325663Z","end":"2026-05-21T03:03:13.711847Z","steps":["trace[426954472] 'range keys from in-memory index tree' (duration: 385.943858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:13.711951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:13.32565Z","time spent":"386.284355ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T03:03:13.713682Z","caller":"traceutil/trace.go:171","msg":"trace[1540006524] linearizableReadLoop","detail":"{readStateIndex:3500; appliedIndex:3499; }","duration":"336.372137ms","start":"2026-05-21T03:03:13.377295Z","end":"2026-05-21T03:03:13.713667Z","steps":["trace[1540006524] 'read index received' (duration: 332.104797ms)","trace[1540006524] 'applied index is now lower than readState.Index' (duration: 4.26624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:03:13.713882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.578671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:8139"} {"level":"info","ts":"2026-05-21T03:03:13.713917Z","caller":"traceutil/trace.go:171","msg":"trace[2082853212] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:3371; }","duration":"336.659093ms","start":"2026-05-21T03:03:13.377249Z","end":"2026-05-21T03:03:13.713908Z","steps":["trace[2082853212] 'agreement among raft nodes before linearized reading' (duration: 336.52879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:13.713945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:13.377227Z","time spent":"336.711265ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":8162,"request content":"key:\"/registry/pods/auth-system/keycloak-0\" "} {"level":"warn","ts":"2026-05-21T03:03:13.714417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.518717ms","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:5"} {"level":"info","ts":"2026-05-21T03:03:13.714497Z","caller":"traceutil/trace.go:171","msg":"trace[174028544] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:3371; }","duration":"251.63115ms","start":"2026-05-21T03:03:13.462845Z","end":"2026-05-21T03:03:13.714476Z","steps":["trace[174028544] 'agreement among raft nodes before linearized reading' (duration: 251.528158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:13.714488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.823795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-21T03:03:13.714579Z","caller":"traceutil/trace.go:171","msg":"trace[1800059359] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3371; }","duration":"301.951398ms","start":"2026-05-21T03:03:13.412615Z","end":"2026-05-21T03:03:13.714566Z","steps":["trace[1800059359] 'agreement among raft nodes before linearized reading' (duration: 301.791455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:13.714757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.515117ms","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-05-21T03:03:13.714795Z","caller":"traceutil/trace.go:171","msg":"trace[114684767] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3371; }","duration":"173.586508ms","start":"2026-05-21T03:03:13.541199Z","end":"2026-05-21T03:03:13.714786Z","steps":["trace[114684767] 'agreement among raft nodes before linearized reading' (duration: 173.479156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:13.71479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:13.412595Z","time spent":"302.070741ms","remote":"127.0.0.1:42204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-21T03:03:14.219337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265973495438183,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T03:03:14.609221Z","caller":"traceutil/trace.go:171","msg":"trace[2072122750] transaction","detail":"{read_only:false; response_revision:3372; number_of_response:1; }","duration":"891.178708ms","start":"2026-05-21T03:03:13.71802Z","end":"2026-05-21T03:03:14.609198Z","steps":["trace[2072122750] 'process raft request' (duration: 891.020525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:14.609376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:13.718003Z","time spent":"891.296981ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:03:14.613824Z","caller":"traceutil/trace.go:171","msg":"trace[308985528] transaction","detail":"{read_only:false; response_revision:3373; number_of_response:1; }","duration":"890.616225ms","start":"2026-05-21T03:03:13.723186Z","end":"2026-05-21T03:03:14.613802Z","steps":["trace[308985528] 'process raft request' (duration: 890.446051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:14.614024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:13.72318Z","time spent":"890.718507ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8124,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:03:14.614215Z","caller":"traceutil/trace.go:171","msg":"trace[1703354309] transaction","detail":"{read_only:false; response_revision:3374; number_of_response:1; }","duration":"229.006768ms","start":"2026-05-21T03:03:14.38518Z","end":"2026-05-21T03:03:14.614187Z","steps":["trace[1703354309] 'process raft request' (duration: 228.574598ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:14.614292Z","caller":"traceutil/trace.go:171","msg":"trace[55863234] transaction","detail":"{read_only:false; response_revision:3375; number_of_response:1; }","duration":"165.899318ms","start":"2026-05-21T03:03:14.448363Z","end":"2026-05-21T03:03:14.614262Z","steps":["trace[55863234] 'process raft request' (duration: 165.709733ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:14.614344Z","caller":"traceutil/trace.go:171","msg":"trace[1331459982] linearizableReadLoop","detail":"{readStateIndex:3502; appliedIndex:3500; }","duration":"896.059311ms","start":"2026-05-21T03:03:13.718273Z","end":"2026-05-21T03:03:14.614332Z","steps":["trace[1331459982] 'read index received' (duration: 890.778289ms)","trace[1331459982] 'applied index is now lower than readState.Index' (duration: 5.280022ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:03:14.614392Z","caller":"traceutil/trace.go:171","msg":"trace[60482438] transaction","detail":"{read_only:false; response_revision:3376; number_of_response:1; }","duration":"164.518796ms","start":"2026-05-21T03:03:14.449856Z","end":"2026-05-21T03:03:14.614375Z","steps":["trace[60482438] 'process raft request' (duration: 164.353783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:14.61453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.234625ms","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-05-21T03:03:14.614743Z","caller":"traceutil/trace.go:171","msg":"trace[357428230] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3376; }","duration":"896.47674ms","start":"2026-05-21T03:03:13.718257Z","end":"2026-05-21T03:03:14.614733Z","steps":["trace[357428230] 'agreement among raft nodes before linearized reading' (duration: 896.114352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:14.614769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:13.71825Z","time spent":"896.510971ms","remote":"127.0.0.1:42198","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-05-21T03:03:14.614923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.681985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:03:14.614998Z","caller":"traceutil/trace.go:171","msg":"trace[916172005] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:3376; }","duration":"815.819018ms","start":"2026-05-21T03:03:13.799161Z","end":"2026-05-21T03:03:14.61498Z","steps":["trace[916172005] 'agreement among raft nodes before linearized reading' (duration: 815.561482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:14.615056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:13.799139Z","time spent":"815.90188ms","remote":"127.0.0.1:42084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:03:14.620386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.92492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-05-21T03:03:14.621709Z","caller":"traceutil/trace.go:171","msg":"trace[966887937] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3376; }","duration":"901.289852ms","start":"2026-05-21T03:03:13.7204Z","end":"2026-05-21T03:03:14.621689Z","steps":["trace[966887937] 'agreement among raft nodes before linearized reading' (duration: 894.30852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:14.621866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:13.720394Z","time spent":"901.429625ms","remote":"127.0.0.1:42204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1338,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-05-21T03:05:19.208893Z","caller":"traceutil/trace.go:171","msg":"trace[2083644875] linearizableReadLoop","detail":"{readStateIndex:4829; appliedIndex:4828; }","duration":"121.945258ms","start":"2026-05-21T03:05:19.086931Z","end":"2026-05-21T03:05:19.208877Z","steps":["trace[2083644875] 'read index received' (duration: 121.777254ms)","trace[2083644875] 'applied index is now lower than readState.Index' (duration: 167.014µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:05:19.20908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.118451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-21T03:05:19.209102Z","caller":"traceutil/trace.go:171","msg":"trace[1472665664] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4649; }","duration":"122.179881ms","start":"2026-05-21T03:05:19.086916Z","end":"2026-05-21T03:05:19.209096Z","steps":["trace[1472665664] 'agreement among raft nodes before linearized reading' (duration: 122.037579ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:19.209253Z","caller":"traceutil/trace.go:171","msg":"trace[1591375119] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"123.31836ms","start":"2026-05-21T03:05:19.085899Z","end":"2026-05-21T03:05:19.209217Z","steps":["trace[1591375119] 'process raft request' (duration: 122.865902ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:20.547182Z","caller":"traceutil/trace.go:171","msg":"trace[1842846568] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"455.109114ms","start":"2026-05-21T03:05:20.092045Z","end":"2026-05-21T03:05:20.547154Z","steps":["trace[1842846568] 'process raft request' (duration: 454.918851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:20.547366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:20.09203Z","time spent":"455.240786ms","remote":"127.0.0.1:36432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7448,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:05:20.600096Z","caller":"traceutil/trace.go:171","msg":"trace[1934629827] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4848; }","duration":"289.714354ms","start":"2026-05-21T03:05:20.310354Z","end":"2026-05-21T03:05:20.600068Z","steps":["trace[1934629827] 'read index received' (duration: 236.668253ms)","trace[1934629827] 'applied index is now lower than readState.Index' (duration: 53.044711ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:05:20.600234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.861016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:05:20.600266Z","caller":"traceutil/trace.go:171","msg":"trace[402841491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4670; }","duration":"289.949178ms","start":"2026-05-21T03:05:20.310307Z","end":"2026-05-21T03:05:20.600256Z","steps":["trace[402841491] 'agreement among raft nodes before linearized reading' (duration: 289.866987ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:20.600242Z","caller":"traceutil/trace.go:171","msg":"trace[1134222801] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"503.189639ms","start":"2026-05-21T03:05:20.097014Z","end":"2026-05-21T03:05:20.600203Z","steps":["trace[1134222801] 'process raft request' (duration: 502.846163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:20.600428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:20.096988Z","time spent":"503.335282ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3877,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:05:22.84778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265973495441288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:05:23.348838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265973495441288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:05:23.849448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265973495441288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:05:23.913966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.79817ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:05:23.914156Z","caller":"traceutil/trace.go:171","msg":"trace[1142198077] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4718; }","duration":"404.038454ms","start":"2026-05-21T03:05:23.510093Z","end":"2026-05-21T03:05:23.914132Z","steps":["trace[1142198077] 'range keys from in-memory index tree' (duration: 403.77904ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:23.916082Z","caller":"traceutil/trace.go:171","msg":"trace[1481237457] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4719; }","duration":"1.598578316s","start":"2026-05-21T03:05:22.317481Z","end":"2026-05-21T03:05:23.916059Z","steps":["trace[1481237457] 'process raft request' (duration: 1.594268442s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:23.916272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:22.317459Z","time spent":"1.598682599s","remote":"127.0.0.1:50932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":117,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-05-21T03:05:23.917452Z","caller":"traceutil/trace.go:171","msg":"trace[312059088] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"653.051529ms","start":"2026-05-21T03:05:23.264384Z","end":"2026-05-21T03:05:23.917435Z","steps":["trace[312059088] 'process raft request' (duration: 652.962578ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:23.91757Z","caller":"traceutil/trace.go:171","msg":"trace[935082660] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"1.598678837s","start":"2026-05-21T03:05:22.318876Z","end":"2026-05-21T03:05:23.917555Z","steps":["trace[935082660] 'process raft request' (duration: 1.598292431s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:23.917633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:22.318859Z","time spent":"1.598739468s","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:05:23.917704Z","caller":"traceutil/trace.go:171","msg":"trace[499103919] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"653.224822ms","start":"2026-05-21T03:05:23.26446Z","end":"2026-05-21T03:05:23.917685Z","steps":["trace[499103919] 'process raft request' (duration: 652.939768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:23.917803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:23.264427Z","time spent":"653.327674ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:05:23.917597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:23.264357Z","time spent":"653.144051ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:05:23.920108Z","caller":"traceutil/trace.go:171","msg":"trace[1343810186] linearizableReadLoop","detail":"{readStateIndex:4907; appliedIndex:4901; }","duration":"1.572947577s","start":"2026-05-21T03:05:22.34715Z","end":"2026-05-21T03:05:23.920098Z","steps":["trace[1343810186] 'read index received' (duration: 1.564613614s)","trace[1343810186] 'applied index is now lower than readState.Index' (duration: 8.332993ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:05:23.920182Z","caller":"traceutil/trace.go:171","msg":"trace[1442784113] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"264.841446ms","start":"2026-05-21T03:05:23.655334Z","end":"2026-05-21T03:05:23.920175Z","steps":["trace[1442784113] 'process raft request' (duration: 264.664483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:23.920404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573254801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-21922bf3-f866-4629-ada6-e4b13962e784\" ","response":"range_response_count:1 size:2242"} {"level":"info","ts":"2026-05-21T03:05:23.920434Z","caller":"traceutil/trace.go:171","msg":"trace[1860711633] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-21922bf3-f866-4629-ada6-e4b13962e784; range_end:; response_count:1; response_revision:4723; }","duration":"1.573317443s","start":"2026-05-21T03:05:22.347108Z","end":"2026-05-21T03:05:23.920425Z","steps":["trace[1860711633] 'agreement among raft nodes before linearized reading' (duration: 1.573170691s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:23.920454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:22.347089Z","time spent":"1.573359553s","remote":"127.0.0.1:50932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":1,"response size":2265,"request content":"key:\"/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-21922bf3-f866-4629-ada6-e4b13962e784\" "} {"level":"warn","ts":"2026-05-21T03:05:23.920563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274763503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-6t5w2\" ","response":"range_response_count:1 size:5149"} {"level":"info","ts":"2026-05-21T03:05:23.920603Z","caller":"traceutil/trace.go:171","msg":"trace[1655347871] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-6t5w2; range_end:; response_count:1; response_revision:4723; }","duration":"1.274843115s","start":"2026-05-21T03:05:22.64575Z","end":"2026-05-21T03:05:23.920593Z","steps":["trace[1655347871] 'agreement among raft nodes before linearized reading' (duration: 1.274657522s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:23.920691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:22.645727Z","time spent":"1.274954986s","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":5172,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-6t5w2\" "} {"level":"warn","ts":"2026-05-21T03:05:23.920917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.678456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-21T03:05:23.920945Z","caller":"traceutil/trace.go:171","msg":"trace[366498479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4723; }","duration":"596.726787ms","start":"2026-05-21T03:05:23.324211Z","end":"2026-05-21T03:05:23.920938Z","steps":["trace[366498479] 'agreement among raft nodes before linearized reading' (duration: 596.650445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:23.920966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:23.324201Z","time spent":"596.760758ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T03:05:23.921065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.644865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:05:23.921111Z","caller":"traceutil/trace.go:171","msg":"trace[80573685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4723; }","duration":"610.724766ms","start":"2026-05-21T03:05:23.310379Z","end":"2026-05-21T03:05:23.921104Z","steps":["trace[80573685] 'agreement among raft nodes before linearized reading' (duration: 610.665915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:23.921133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:23.310359Z","time spent":"610.767877ms","remote":"127.0.0.1:42036","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-05-21T03:05:23.921141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208989338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e0926f1-861a-429f-894e-4039cad7ac1a\" ","response":"range_response_count:1 size:4078"} {"level":"info","ts":"2026-05-21T03:05:23.92117Z","caller":"traceutil/trace.go:171","msg":"trace[468726962] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7e0926f1-861a-429f-894e-4039cad7ac1a; range_end:; response_count:1; response_revision:4723; }","duration":"1.209061459s","start":"2026-05-21T03:05:22.712099Z","end":"2026-05-21T03:05:23.921161Z","steps":["trace[468726962] 'agreement among raft nodes before linearized reading' (duration: 1.208976538s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:23.921197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:22.712076Z","time spent":"1.20911632s","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4101,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e0926f1-861a-429f-894e-4039cad7ac1a\" "} {"level":"warn","ts":"2026-05-21T03:05:23.921329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.132895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21922bf3-f866-4629-ada6-e4b13962e784\" ","response":"range_response_count:1 size:4051"} {"level":"info","ts":"2026-05-21T03:05:23.921362Z","caller":"traceutil/trace.go:171","msg":"trace[1332537301] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-21922bf3-f866-4629-ada6-e4b13962e784; range_end:; response_count:1; response_revision:4723; }","duration":"851.207655ms","start":"2026-05-21T03:05:23.070146Z","end":"2026-05-21T03:05:23.921354Z","steps":["trace[1332537301] 'agreement among raft nodes before linearized reading' (duration: 851.119214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:23.921457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:05:23.070119Z","time spent":"851.330178ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4074,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21922bf3-f866-4629-ada6-e4b13962e784\" "} {"level":"info","ts":"2026-05-21T03:05:31.472179Z","caller":"traceutil/trace.go:171","msg":"trace[143035624] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"136.097906ms","start":"2026-05-21T03:05:31.336029Z","end":"2026-05-21T03:05:31.472127Z","steps":["trace[143035624] 'process raft request' (duration: 75.788009ms)","trace[143035624] 'compare' (duration: 60.170465ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:05:31.474038Z","caller":"traceutil/trace.go:171","msg":"trace[99019908] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"107.302611ms","start":"2026-05-21T03:05:31.366715Z","end":"2026-05-21T03:05:31.474018Z","steps":["trace[99019908] 'process raft request' (duration: 106.952416ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:31.474031Z","caller":"traceutil/trace.go:171","msg":"trace[31115372] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"108.040874ms","start":"2026-05-21T03:05:31.365956Z","end":"2026-05-21T03:05:31.473997Z","steps":["trace[31115372] 'process raft request' (duration: 107.471605ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:31.474059Z","caller":"traceutil/trace.go:171","msg":"trace[461931861] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"107.177529ms","start":"2026-05-21T03:05:31.366867Z","end":"2026-05-21T03:05:31.474045Z","steps":["trace[461931861] 'process raft request' (duration: 106.832503ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:31.474155Z","caller":"traceutil/trace.go:171","msg":"trace[522412052] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"107.566026ms","start":"2026-05-21T03:05:31.366499Z","end":"2026-05-21T03:05:31.474065Z","steps":["trace[522412052] 'process raft request' (duration: 107.114569ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:37.623377Z","caller":"traceutil/trace.go:171","msg":"trace[2020410161] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"166.679569ms","start":"2026-05-21T03:05:37.45663Z","end":"2026-05-21T03:05:37.62331Z","steps":["trace[2020410161] 'process raft request' (duration: 166.489176ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:22.368136Z","caller":"traceutil/trace.go:171","msg":"trace[744765074] linearizableReadLoop","detail":"{readStateIndex:5579; appliedIndex:5578; }","duration":"136.161422ms","start":"2026-05-21T03:06:22.231938Z","end":"2026-05-21T03:06:22.368099Z","steps":["trace[744765074] 'read index received' (duration: 125.123419ms)","trace[744765074] 'applied index is now lower than readState.Index' (duration: 11.036773ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:06:22.368174Z","caller":"traceutil/trace.go:171","msg":"trace[957605174] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"158.855831ms","start":"2026-05-21T03:06:22.20929Z","end":"2026-05-21T03:06:22.368146Z","steps":["trace[957605174] 'process raft request' (duration: 147.823927ms)","trace[957605174] 'compare' (duration: 10.726739ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:06:22.368439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.491628ms","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-05-21T03:06:22.368479Z","caller":"traceutil/trace.go:171","msg":"trace[1336448973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5377; }","duration":"136.59695ms","start":"2026-05-21T03:06:22.231872Z","end":"2026-05-21T03:06:22.368469Z","steps":["trace[1336448973] 'agreement among raft nodes before linearized reading' (duration: 136.380666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:06:22.368563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.676204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-21T03:06:22.36865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.121439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T03:06:22.368666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.394876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T03:06:22.368704Z","caller":"traceutil/trace.go:171","msg":"trace[1205479104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5377; }","duration":"122.476286ms","start":"2026-05-21T03:06:22.246219Z","end":"2026-05-21T03:06:22.368695Z","steps":["trace[1205479104] 'agreement among raft nodes before linearized reading' (duration: 122.350804ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:22.368707Z","caller":"traceutil/trace.go:171","msg":"trace[1225490069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5377; }","duration":"120.2119ms","start":"2026-05-21T03:06:22.248481Z","end":"2026-05-21T03:06:22.368693Z","steps":["trace[1225490069] 'agreement among raft nodes before linearized reading' (duration: 120.065798ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:22.368679Z","caller":"traceutil/trace.go:171","msg":"trace[2134708032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5377; }","duration":"126.865247ms","start":"2026-05-21T03:06:22.241794Z","end":"2026-05-21T03:06:22.36866Z","steps":["trace[2134708032] 'agreement among raft nodes before linearized reading' (duration: 126.559322ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:06:35.207046Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-05-21T03:06:35.309366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"101.169188ms","hash":779012553,"current-db-size-bytes":52957184,"current-db-size":"53 MB","current-db-size-in-use-bytes":28536832,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-21T03:06:35.309432Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":779012553,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-05-21T03:11:35.213873Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5475} {"level":"info","ts":"2026-05-21T03:11:35.318203Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5475,"took":"102.297211ms","hash":3102262416,"current-db-size-bytes":52957184,"current-db-size":"53 MB","current-db-size-in-use-bytes":18485248,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-21T03:11:35.318264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3102262416,"revision":5475,"compact-revision":2862} {"level":"warn","ts":"2026-05-21T03:12:46.49785Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265973495450587,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:12:47.046541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265973495450587,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:12:47.056852Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.289222235s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T03:12:47.057349Z","caller":"traceutil/trace.go:171","msg":"trace[320950621] transaction","detail":"{read_only:false; response_revision:7735; number_of_response:1; }","duration":"1.289818202s","start":"2026-05-21T03:12:45.767506Z","end":"2026-05-21T03:12:47.057324Z","steps":["trace[320950621] 'process raft request' (duration: 1.289660158s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.05762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:45.767494Z","time spent":"1.289947696s","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:12:47.058974Z","caller":"traceutil/trace.go:171","msg":"trace[1996193156] transaction","detail":"{read_only:false; response_revision:7736; number_of_response:1; }","duration":"1.291220465s","start":"2026-05-21T03:12:45.767739Z","end":"2026-05-21T03:12:47.05896Z","steps":["trace[1996193156] 'process raft request' (duration: 1.291094581s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.059047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:45.767725Z","time spent":"1.291288487s","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:12:47.059189Z","caller":"traceutil/trace.go:171","msg":"trace[1887897125] transaction","detail":"{read_only:false; response_revision:7737; number_of_response:1; }","duration":"1.288921025s","start":"2026-05-21T03:12:45.770261Z","end":"2026-05-21T03:12:47.059182Z","steps":["trace[1887897125] 'process raft request' (duration: 1.288660228s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.059233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:45.77025Z","time spent":"1.288961866s","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:12:47.059638Z","caller":"traceutil/trace.go:171","msg":"trace[1300294389] transaction","detail":"{read_only:false; response_revision:7738; number_of_response:1; }","duration":"513.189004ms","start":"2026-05-21T03:12:46.546421Z","end":"2026-05-21T03:12:47.05961Z","steps":["trace[1300294389] 'process raft request' (duration: 512.72132ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:12:47.059858Z","caller":"traceutil/trace.go:171","msg":"trace[158203603] linearizableReadLoop","detail":"{readStateIndex:8029; appliedIndex:8025; }","duration":"1.072702333s","start":"2026-05-21T03:12:45.987126Z","end":"2026-05-21T03:12:47.059829Z","steps":["trace[158203603] 'read index received' (duration: 1.069965231s)","trace[158203603] 'applied index is now lower than readState.Index' (duration: 2.734601ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:12:47.059901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:46.546375Z","time spent":"513.345088ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:12:47.060233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013404553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:688"} {"level":"warn","ts":"2026-05-21T03:12:47.060243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073105864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-21T03:12:47.060285Z","caller":"traceutil/trace.go:171","msg":"trace[1951533201] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:7739; }","duration":"1.013504305s","start":"2026-05-21T03:12:46.046767Z","end":"2026-05-21T03:12:47.060271Z","steps":["trace[1951533201] 'agreement among raft nodes before linearized reading' (duration: 1.013380191s)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:12:47.060297Z","caller":"traceutil/trace.go:171","msg":"trace[968115915] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:7739; }","duration":"1.073161966s","start":"2026-05-21T03:12:45.987121Z","end":"2026-05-21T03:12:47.060283Z","steps":["trace[968115915] 'agreement among raft nodes before linearized reading' (duration: 1.07296703s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.060318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:46.046754Z","time spent":"1.013555507s","remote":"127.0.0.1:42336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":711,"request content":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T03:12:47.060338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:45.987073Z","time spent":"1.073257349s","remote":"127.0.0.1:42336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":380,"request content":"key:\"/registry/roles/kube-system/kubeadm:nodes-kubeadm-config\" "} {"level":"warn","ts":"2026-05-21T03:12:47.060698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.20193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T03:12:47.060732Z","caller":"traceutil/trace.go:171","msg":"trace[1550255322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7739; }","duration":"333.275273ms","start":"2026-05-21T03:12:46.727449Z","end":"2026-05-21T03:12:47.060724Z","steps":["trace[1550255322] 'agreement among raft nodes before linearized reading' (duration: 333.198931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.060751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:46.727405Z","time spent":"333.342135ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T03:12:47.060931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.713319ms","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-05-21T03:12:47.06096Z","caller":"traceutil/trace.go:171","msg":"trace[1648765066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7739; }","duration":"458.74404ms","start":"2026-05-21T03:12:46.602208Z","end":"2026-05-21T03:12:47.060952Z","steps":["trace[1648765066] 'agreement among raft nodes before linearized reading' (duration: 458.668497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.060984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:46.602163Z","time spent":"458.816112ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T03:12:47.06116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.877266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-21T03:12:47.061187Z","caller":"traceutil/trace.go:171","msg":"trace[261831728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7739; }","duration":"535.970849ms","start":"2026-05-21T03:12:46.52521Z","end":"2026-05-21T03:12:47.061181Z","steps":["trace[261831728] 'agreement among raft nodes before linearized reading' (duration: 535.904397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.061205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:46.525179Z","time spent":"536.02159ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T03:12:47.06145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.809744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T03:12:47.061477Z","caller":"traceutil/trace.go:171","msg":"trace[1800980297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7739; }","duration":"567.836495ms","start":"2026-05-21T03:12:46.493634Z","end":"2026-05-21T03:12:47.061471Z","steps":["trace[1800980297] 'agreement among raft nodes before linearized reading' (duration: 567.768023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.061496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:46.493607Z","time spent":"567.884777ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T03:12:47.062081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.979124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:12:47.062118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.060461ms","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:5"} {"level":"info","ts":"2026-05-21T03:12:47.062151Z","caller":"traceutil/trace.go:171","msg":"trace[890641014] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:7739; }","duration":"564.109142ms","start":"2026-05-21T03:12:46.498034Z","end":"2026-05-21T03:12:47.062143Z","steps":["trace[890641014] 'agreement among raft nodes before linearized reading' (duration: 563.509684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.062174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:46.49803Z","time spent":"564.137883ms","remote":"127.0.0.1:50986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:12:47.062516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.433671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T03:12:47.062579Z","caller":"traceutil/trace.go:171","msg":"trace[1161400002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7739; }","duration":"931.523933ms","start":"2026-05-21T03:12:46.131036Z","end":"2026-05-21T03:12:47.06256Z","steps":["trace[1161400002] 'agreement among raft nodes before linearized reading' (duration: 931.319987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.062724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:46.131018Z","time spent":"931.652597ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T03:12:47.062156Z","caller":"traceutil/trace.go:171","msg":"trace[2003469501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7739; }","duration":"752.088837ms","start":"2026-05-21T03:12:46.31005Z","end":"2026-05-21T03:12:47.062139Z","steps":["trace[2003469501] 'agreement among raft nodes before linearized reading' (duration: 751.974564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:12:47.063083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:12:46.310034Z","time spent":"752.908981ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}